{"level":"warn","ts":"2026-07-06T23:13:25.80652Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:13:25.806627Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-07-06T23:13:25.806867Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:13:25.806886Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-07-06T23:13:25.806911Z","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-07-06T23:13:25.807748Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-07-06T23:13:25.807819Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-07-06T23:13:25.811419Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.364811ms"} {"level":"info","ts":"2026-07-06T23:13:25.816461Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-07-06T23:13:25.816517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T23:13:25.816533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-07-06T23:13:25.816543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T23:13:25.816553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-07-06T23:13:25.816582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-07-06T23:13:25.82815Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T23:13:25.829907Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T23:13:25.830944Z","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-07-06T23:13:25.832891Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T23:13:25.833Z","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-07-06T23:13:25.833112Z","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-07-06T23:13:25.83313Z","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-07-06T23:13:25.833056Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T23:13:25.835003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-07-06T23:13:25.83515Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-07-06T23:13:25.839617Z","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-07-06T23:13:25.83982Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-06T23:13:25.839905Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-07-06T23:13:25.840155Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T23:13:25.84021Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T23:13:26.716772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T23:13:26.716854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T23:13:26.716882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-07-06T23:13:26.716896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-07-06T23:13:26.716904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-06T23:13:26.716915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-07-06T23:13:26.716929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-07-06T23:13:26.718177Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:26.719085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:13:26.719087Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T23:13:26.719129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:13:26.719474Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T23:13:26.719596Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T23:13:26.719606Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:26.719795Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:26.719862Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:13:26.721582Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-07-06T23:13:26.721897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-06T23:13:37.876845Z","caller":"traceutil/trace.go:171","msg":"trace[403180372] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"185.02951ms","start":"2026-07-06T23:13:37.691788Z","end":"2026-07-06T23:13:37.876817Z","steps":["trace[403180372] 'process raft request' (duration: 184.865896ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:13:38.030727Z","caller":"traceutil/trace.go:171","msg":"trace[465303774] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"145.624924ms","start":"2026-07-06T23:13:37.88506Z","end":"2026-07-06T23:13:38.030685Z","steps":["trace[465303774] 'process raft request' (duration: 117.113621ms)","trace[465303774] 'compare' (duration: 28.129673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:13:38.318378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.172903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:13:38.318671Z","caller":"traceutil/trace.go:171","msg":"trace[264681150] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"279.241636ms","start":"2026-07-06T23:13:38.039387Z","end":"2026-07-06T23:13:38.318628Z","steps":["trace[264681150] 'process raft request' (duration: 114.201361ms)","trace[264681150] 'compare' (duration: 164.02701ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:13:38.318885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.59697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-06T23:13:38.31896Z","caller":"traceutil/trace.go:171","msg":"trace[1008977832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"116.744154ms","start":"2026-07-06T23:13:38.202198Z","end":"2026-07-06T23:13:38.318942Z","steps":["trace[1008977832] 'agreement among raft nodes before linearized reading' (duration: 116.58645ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:13:38.318695Z","caller":"traceutil/trace.go:171","msg":"trace[361366423] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"116.414716ms","start":"2026-07-06T23:13:38.202246Z","end":"2026-07-06T23:13:38.318661Z","steps":["trace[361366423] 'read index received' (duration: 45.191µs)","trace[361366423] 'applied index is now lower than readState.Index' (duration: 116.366595ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:13:38.319505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.042601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:13:38.319556Z","caller":"traceutil/trace.go:171","msg":"trace[1902677196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"117.138933ms","start":"2026-07-06T23:13:38.202401Z","end":"2026-07-06T23:13:38.31954Z","steps":["trace[1902677196] 'agreement among raft nodes before linearized reading' (duration: 117.047611ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:03.918389Z","caller":"traceutil/trace.go:171","msg":"trace[1554580997] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"108.297081ms","start":"2026-07-06T23:14:03.810068Z","end":"2026-07-06T23:14:03.918365Z","steps":["trace[1554580997] 'process raft request' (duration: 108.23259ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:03.918398Z","caller":"traceutil/trace.go:171","msg":"trace[836903364] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.416134ms","start":"2026-07-06T23:14:03.809953Z","end":"2026-07-06T23:14:03.918369Z","steps":["trace[836903364] 'process raft request' (duration: 80.152568ms)","trace[836903364] 'compare' (duration: 27.98588ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:14:03.919641Z","caller":"traceutil/trace.go:171","msg":"trace[1797786087] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"106.799264ms","start":"2026-07-06T23:14:03.812809Z","end":"2026-07-06T23:14:03.919608Z","steps":["trace[1797786087] 'process raft request' (duration: 106.596689ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:03.919785Z","caller":"traceutil/trace.go:171","msg":"trace[1800178752] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"104.710173ms","start":"2026-07-06T23:14:03.815065Z","end":"2026-07-06T23:14:03.919776Z","steps":["trace[1800178752] 'process raft request' (duration: 104.440165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:10.098361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.339798ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-06T23:14:10.234292Z","caller":"traceutil/trace.go:171","msg":"trace[895023507] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"133.493852ms","start":"2026-07-06T23:14:10.100758Z","end":"2026-07-06T23:14:10.234195Z","steps":["trace[895023507] 'process raft request' (duration: 128.877738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:10.812341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.172557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:14:10.812472Z","caller":"traceutil/trace.go:171","msg":"trace[89812339] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"244.371836ms","start":"2026-07-06T23:14:10.56808Z","end":"2026-07-06T23:14:10.812452Z","steps":["trace[89812339] 'read index received' (duration: 16.18239ms)","trace[89812339] 'applied index is now lower than readState.Index' (duration: 228.188286ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:14:10.81268Z","caller":"traceutil/trace.go:171","msg":"trace[633059952] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"283.259924ms","start":"2026-07-06T23:14:10.529407Z","end":"2026-07-06T23:14:10.812667Z","steps":["trace[633059952] 'process raft request' (duration: 161.650076ms)","trace[633059952] 'compare' (duration: 120.928492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:14:10.812974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.864768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-06T23:14:10.813109Z","caller":"traceutil/trace.go:171","msg":"trace[922017158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"244.99561ms","start":"2026-07-06T23:14:10.56805Z","end":"2026-07-06T23:14:10.813046Z","steps":["trace[922017158] 'agreement among raft nodes before linearized reading' (duration: 244.723114ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:11.094993Z","caller":"traceutil/trace.go:171","msg":"trace[1975416118] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"372.122246ms","start":"2026-07-06T23:14:10.722794Z","end":"2026-07-06T23:14:11.094916Z","steps":["trace[1975416118] 'process raft request' (duration: 342.919205ms)","trace[1975416118] 'compare' (duration: 25.09093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:14:11.095255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.817589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:14:11.09523Z","caller":"traceutil/trace.go:171","msg":"trace[105947187] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"282.546797ms","start":"2026-07-06T23:14:10.812501Z","end":"2026-07-06T23:14:11.095048Z","steps":["trace[105947187] 'read index received' (duration: 252.120677ms)","trace[105947187] 'applied index is now lower than readState.Index' (duration: 30.42104ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:14:11.095323Z","caller":"traceutil/trace.go:171","msg":"trace[1768875945] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"277.736858ms","start":"2026-07-06T23:14:10.817555Z","end":"2026-07-06T23:14:11.095292Z","steps":["trace[1768875945] 'process raft request' (duration: 277.344429ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:14:11.095405Z","caller":"traceutil/trace.go:171","msg":"trace[2093967585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"444.972822ms","start":"2026-07-06T23:14:10.650352Z","end":"2026-07-06T23:14:11.095325Z","steps":["trace[2093967585] 'agreement among raft nodes before linearized reading' (duration: 444.792518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:14:11.09522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:10.722769Z","time spent":"372.343462ms","remote":"127.0.0.1:35344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:14:11.095555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:14:10.650324Z","time spent":"445.186288ms","remote":"127.0.0.1:35234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T23:14:13.069894Z","caller":"traceutil/trace.go:171","msg":"trace[38664910] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"113.442618ms","start":"2026-07-06T23:14:12.956426Z","end":"2026-07-06T23:14:13.069869Z","steps":["trace[38664910] 'process raft request' (duration: 113.100229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:40.088531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.319065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e3740c1-fbde-46cc-9805-b1dd55956659\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-06T23:15:40.088605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.092369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-06T23:15:40.088636Z","caller":"traceutil/trace.go:171","msg":"trace[72342202] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e3740c1-fbde-46cc-9805-b1dd55956659; range_end:; response_count:1; response_revision:1867; }","duration":"270.476279ms","start":"2026-07-06T23:15:39.818136Z","end":"2026-07-06T23:15:40.088613Z","steps":["trace[72342202] 'range keys from in-memory index tree' (duration: 270.123641ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:15:40.08869Z","caller":"traceutil/trace.go:171","msg":"trace[2052524002] range","detail":"{range_begin:/registry/masterleases/199.204.45.144; range_end:; response_count:1; response_revision:1867; }","duration":"244.241714ms","start":"2026-07-06T23:15:39.844425Z","end":"2026-07-06T23:15:40.088666Z","steps":["trace[2052524002] 'range keys from in-memory index tree' (duration: 243.879464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:40.441272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.901259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e3740c1-fbde-46cc-9805-b1dd55956659\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-06T23:15:40.441422Z","caller":"traceutil/trace.go:171","msg":"trace[1202400808] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e3740c1-fbde-46cc-9805-b1dd55956659; range_end:; response_count:1; response_revision:1868; }","duration":"198.112754ms","start":"2026-07-06T23:15:40.243279Z","end":"2026-07-06T23:15:40.441392Z","steps":["trace[1202400808] 'range keys from in-memory index tree' (duration: 197.605771ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:15:47.541064Z","caller":"traceutil/trace.go:171","msg":"trace[920800892] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"160.969964ms","start":"2026-07-06T23:15:47.380073Z","end":"2026-07-06T23:15:47.541043Z","steps":["trace[920800892] 'read index received' (duration: 160.736028ms)","trace[920800892] 'applied index is now lower than readState.Index' (duration: 233.056µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:15:47.541133Z","caller":"traceutil/trace.go:171","msg":"trace[1085111147] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"236.023593ms","start":"2026-07-06T23:15:47.305081Z","end":"2026-07-06T23:15:47.541104Z","steps":["trace[1085111147] 'process raft request' (duration: 235.822698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:47.541237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.46636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T23:15:47.541283Z","caller":"traceutil/trace.go:171","msg":"trace[1733995701] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1935; }","duration":"141.573453ms","start":"2026-07-06T23:15:47.399698Z","end":"2026-07-06T23:15:47.541271Z","steps":["trace[1733995701] 'agreement among raft nodes before linearized reading' (duration: 141.46759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:47.541292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.192209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-06T23:15:47.541373Z","caller":"traceutil/trace.go:171","msg":"trace[1589605414] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1935; }","duration":"161.319322ms","start":"2026-07-06T23:15:47.380032Z","end":"2026-07-06T23:15:47.541352Z","steps":["trace[1589605414] 'agreement among raft nodes before linearized reading' (duration: 161.099717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:15:53.105617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.556488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:15:53.105721Z","caller":"traceutil/trace.go:171","msg":"trace[160657977] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:1972; }","duration":"100.693192ms","start":"2026-07-06T23:15:53.005Z","end":"2026-07-06T23:15:53.105694Z","steps":["trace[160657977] 'count revisions from in-memory index tree' (duration: 100.441226ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:19.514773Z","caller":"traceutil/trace.go:171","msg":"trace[1100227303] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"128.318691ms","start":"2026-07-06T23:16:19.386403Z","end":"2026-07-06T23:16:19.514722Z","steps":["trace[1100227303] 'process raft request' (duration: 127.931251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:25.279701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.518632ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T23:16:25.279833Z","caller":"traceutil/trace.go:171","msg":"trace[613469186] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2243; }","duration":"114.324031ms","start":"2026-07-06T23:16:25.165487Z","end":"2026-07-06T23:16:25.279811Z","steps":["trace[613469186] 'read index received' (duration: 74.292µs)","trace[613469186] 'applied index is now lower than readState.Index' (duration: 114.247849ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:25.280198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.67948ms","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-07-06T23:16:25.280966Z","caller":"traceutil/trace.go:171","msg":"trace[1192068680] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2162; }","duration":"115.526171ms","start":"2026-07-06T23:16:25.165401Z","end":"2026-07-06T23:16:25.280927Z","steps":["trace[1192068680] 'agreement among raft nodes before linearized reading' (duration: 114.499686ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:31.441215Z","caller":"traceutil/trace.go:171","msg":"trace[2077364983] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2275; }","duration":"113.736169ms","start":"2026-07-06T23:16:31.327455Z","end":"2026-07-06T23:16:31.441191Z","steps":["trace[2077364983] 'read index received' (duration: 113.634596ms)","trace[2077364983] 'applied index is now lower than readState.Index' (duration: 100.743µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:31.441324Z","caller":"traceutil/trace.go:171","msg":"trace[1572727124] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"120.281077ms","start":"2026-07-06T23:16:31.321032Z","end":"2026-07-06T23:16:31.441313Z","steps":["trace[1572727124] 'process raft request' (duration: 120.00645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:31.441582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.107517ms","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-07-06T23:16:31.441628Z","caller":"traceutil/trace.go:171","msg":"trace[1087380777] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2193; }","duration":"114.188399ms","start":"2026-07-06T23:16:31.32743Z","end":"2026-07-06T23:16:31.441619Z","steps":["trace[1087380777] 'agreement among raft nodes before linearized reading' (duration: 114.012065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:31.657768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.93154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:31.657866Z","caller":"traceutil/trace.go:171","msg":"trace[280201434] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2193; }","duration":"196.087974ms","start":"2026-07-06T23:16:31.461753Z","end":"2026-07-06T23:16:31.657841Z","steps":["trace[280201434] 'count revisions from in-memory index tree' (duration: 195.824168ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:31.658245Z","caller":"traceutil/trace.go:171","msg":"trace[1678484235] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"137.628186ms","start":"2026-07-06T23:16:31.520598Z","end":"2026-07-06T23:16:31.658226Z","steps":["trace[1678484235] 'process raft request' (duration: 122.714765ms)","trace[1678484235] 'compare' (duration: 14.799369ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:31.658772Z","caller":"traceutil/trace.go:171","msg":"trace[2123219751] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"106.993894ms","start":"2026-07-06T23:16:31.551738Z","end":"2026-07-06T23:16:31.658732Z","steps":["trace[2123219751] 'process raft request' (duration: 106.536253ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:31.780126Z","caller":"traceutil/trace.go:171","msg":"trace[1717797812] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"114.353893ms","start":"2026-07-06T23:16:31.665739Z","end":"2026-07-06T23:16:31.780092Z","steps":["trace[1717797812] 'process raft request' (duration: 92.219956ms)","trace[1717797812] 'compare' (duration: 22.002464ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:00.408953Z","caller":"traceutil/trace.go:171","msg":"trace[199264072] linearizableReadLoop","detail":"{readStateIndex:2981; appliedIndex:2980; }","duration":"150.426077ms","start":"2026-07-06T23:18:00.258477Z","end":"2026-07-06T23:18:00.408903Z","steps":["trace[199264072] 'read index received' (duration: 88.920775ms)","trace[199264072] 'applied index is now lower than readState.Index' (duration: 61.503922ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:00.408986Z","caller":"traceutil/trace.go:171","msg":"trace[425119750] transaction","detail":"{read_only:false; response_revision:2870; number_of_response:1; }","duration":"163.73114ms","start":"2026-07-06T23:18:00.245208Z","end":"2026-07-06T23:18:00.408939Z","steps":["trace[425119750] 'process raft request' (duration: 102.253489ms)","trace[425119750] 'compare' (duration: 61.246645ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:18:00.40955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.974812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:18:00.409662Z","caller":"traceutil/trace.go:171","msg":"trace[1714258302] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2870; }","duration":"151.220747ms","start":"2026-07-06T23:18:00.258426Z","end":"2026-07-06T23:18:00.409646Z","steps":["trace[1714258302] 'agreement among raft nodes before linearized reading' (duration: 150.604841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:00.692309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.940758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:18:00.692593Z","caller":"traceutil/trace.go:171","msg":"trace[1581039960] transaction","detail":"{read_only:false; response_revision:2871; number_of_response:1; }","duration":"208.63079ms","start":"2026-07-06T23:18:00.48393Z","end":"2026-07-06T23:18:00.692561Z","steps":["trace[1581039960] 'process raft request' (duration: 88.329193ms)","trace[1581039960] 'compare' (duration: 119.803244ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:21:42.197691Z","caller":"traceutil/trace.go:171","msg":"trace[466287517] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"103.819561ms","start":"2026-07-06T23:21:42.093851Z","end":"2026-07-06T23:21:42.197671Z","steps":["trace[466287517] 'read index received' (duration: 103.705938ms)","trace[466287517] 'applied index is now lower than readState.Index' (duration: 112.693µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:21:42.197767Z","caller":"traceutil/trace.go:171","msg":"trace[2069761942] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"104.21971ms","start":"2026-07-06T23:21:42.093507Z","end":"2026-07-06T23:21:42.197727Z","steps":["trace[2069761942] 'process raft request' (duration: 104.043876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:21:42.197923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.052526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-07-06T23:21:42.197954Z","caller":"traceutil/trace.go:171","msg":"trace[1977029721] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4716; }","duration":"104.131288ms","start":"2026-07-06T23:21:42.093811Z","end":"2026-07-06T23:21:42.197943Z","steps":["trace[1977029721] 'agreement among raft nodes before linearized reading' (duration: 103.977535ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:21:47.536701Z","caller":"traceutil/trace.go:171","msg":"trace[1655524472] linearizableReadLoop","detail":"{readStateIndex:5058; appliedIndex:5057; }","duration":"232.375077ms","start":"2026-07-06T23:21:47.304302Z","end":"2026-07-06T23:21:47.536678Z","steps":["trace[1655524472] 'read index received' (duration: 216.040497ms)","trace[1655524472] 'applied index is now lower than readState.Index' (duration: 16.33319ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:21:47.536793Z","caller":"traceutil/trace.go:171","msg":"trace[193464889] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"244.264809ms","start":"2026-07-06T23:21:47.292492Z","end":"2026-07-06T23:21:47.536757Z","steps":["trace[193464889] 'process raft request' (duration: 227.874148ms)","trace[193464889] 'compare' (duration: 16.189996ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:21:47.537004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.176215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-06T23:21:47.53705Z","caller":"traceutil/trace.go:171","msg":"trace[860770762] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4870; }","duration":"235.288569ms","start":"2026-07-06T23:21:47.301749Z","end":"2026-07-06T23:21:47.537037Z","steps":["trace[860770762] 'agreement among raft nodes before linearized reading' (duration: 235.082354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:21:47.543119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.766829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:21:47.543208Z","caller":"traceutil/trace.go:171","msg":"trace[277150606] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4871; }","duration":"235.917603ms","start":"2026-07-06T23:21:47.307271Z","end":"2026-07-06T23:21:47.543189Z","steps":["trace[277150606] 'agreement among raft nodes before linearized reading' (duration: 235.762639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:21:47.54324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.414329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jnpr9\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-06T23:21:47.54329Z","caller":"traceutil/trace.go:171","msg":"trace[471526493] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jnpr9; range_end:; response_count:1; response_revision:4871; }","duration":"127.504171ms","start":"2026-07-06T23:21:47.415773Z","end":"2026-07-06T23:21:47.543277Z","steps":["trace[471526493] 'agreement among raft nodes before linearized reading' (duration: 127.369708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:21:50.190435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.069464ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:21:50.190614Z","caller":"traceutil/trace.go:171","msg":"trace[627262964] linearizableReadLoop","detail":"{readStateIndex:5192; appliedIndex:5191; }","duration":"133.6246ms","start":"2026-07-06T23:21:50.056938Z","end":"2026-07-06T23:21:50.190562Z","steps":["trace[627262964] 'read index received' (duration: 46.461µs)","trace[627262964] 'applied index is now lower than readState.Index' (duration: 133.576199ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:21:50.190672Z","caller":"traceutil/trace.go:171","msg":"trace[2061705959] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"167.733945ms","start":"2026-07-06T23:21:50.022907Z","end":"2026-07-06T23:21:50.190641Z","steps":["trace[2061705959] 'process raft request' (duration: 10.356764ms)","trace[2061705959] 'compare' (duration: 156.93134ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:21:50.190852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.906997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-eb86acd6-1232-4982-9daf-2f66a5257178\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-06T23:21:50.19091Z","caller":"traceutil/trace.go:171","msg":"trace[136642586] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-eb86acd6-1232-4982-9daf-2f66a5257178; range_end:; response_count:1; response_revision:5001; }","duration":"133.993929ms","start":"2026-07-06T23:21:50.056902Z","end":"2026-07-06T23:21:50.190895Z","steps":["trace[136642586] 'agreement among raft nodes before linearized reading' (duration: 133.813555ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:21:50.20534Z","caller":"traceutil/trace.go:171","msg":"trace[397727651] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"124.444085ms","start":"2026-07-06T23:21:50.080874Z","end":"2026-07-06T23:21:50.205319Z","steps":["trace[397727651] 'process raft request' (duration: 124.342773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:21:50.205408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.229304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-tddws.18bfd6c26e8ab218\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-07-06T23:21:50.20546Z","caller":"traceutil/trace.go:171","msg":"trace[465136962] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-tddws.18bfd6c26e8ab218; range_end:; response_count:1; response_revision:5002; }","duration":"145.312066ms","start":"2026-07-06T23:21:50.060135Z","end":"2026-07-06T23:21:50.205447Z","steps":["trace[465136962] 'agreement among raft nodes before linearized reading' (duration: 145.165133ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:21:52.300012Z","caller":"traceutil/trace.go:171","msg":"trace[911349884] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"104.076987ms","start":"2026-07-06T23:21:52.195914Z","end":"2026-07-06T23:21:52.299991Z","steps":["trace[911349884] 'process raft request' (duration: 103.929773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:22:04.083698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.929849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-06T23:22:04.083813Z","caller":"traceutil/trace.go:171","msg":"trace[509107662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5266; }","duration":"208.086042ms","start":"2026-07-06T23:22:03.875703Z","end":"2026-07-06T23:22:04.083789Z","steps":["trace[509107662] 'range keys from in-memory index tree' (duration: 207.756534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:22:11.886306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.1921ms","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-07-06T23:22:11.886413Z","caller":"traceutil/trace.go:171","msg":"trace[323261390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5446; }","duration":"115.355074ms","start":"2026-07-06T23:22:11.771038Z","end":"2026-07-06T23:22:11.886393Z","steps":["trace[323261390] 'range keys from in-memory index tree' (duration: 114.984444ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:22:12.469567Z","caller":"traceutil/trace.go:171","msg":"trace[1202825843] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"103.901943ms","start":"2026-07-06T23:22:12.365635Z","end":"2026-07-06T23:22:12.469537Z","steps":["trace[1202825843] 'process raft request' (duration: 103.742019ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:22:12.469616Z","caller":"traceutil/trace.go:171","msg":"trace[1776355799] transaction","detail":"{read_only:false; response_revision:5451; number_of_response:1; }","duration":"102.212832ms","start":"2026-07-06T23:22:12.367376Z","end":"2026-07-06T23:22:12.469588Z","steps":["trace[1776355799] 'process raft request' (duration: 102.100479ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:23:26.742531Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3014} {"level":"info","ts":"2026-07-06T23:23:26.826739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3014,"took":"82.64398ms","hash":3167207902,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":32022528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-06T23:23:26.82685Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3167207902,"revision":3014,"compact-revision":-1} {"level":"info","ts":"2026-07-06T23:28:26.749925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6112} {"level":"info","ts":"2026-07-06T23:28:26.844566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6112,"took":"92.77945ms","hash":3688562287,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":23568384,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-06T23:28:26.844736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3688562287,"revision":6112,"compact-revision":3014} {"level":"warn","ts":"2026-07-06T23:28:32.787645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.332604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-06T23:28:32.787749Z","caller":"traceutil/trace.go:171","msg":"trace[2175372] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:8338; }","duration":"118.524698ms","start":"2026-07-06T23:28:32.669204Z","end":"2026-07-06T23:28:32.787729Z","steps":["trace[2175372] 'range keys from in-memory index tree' (duration: 118.106449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:28:33.795888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.772391ms","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-07-06T23:28:33.795952Z","caller":"traceutil/trace.go:171","msg":"trace[810768414] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8341; }","duration":"102.845723ms","start":"2026-07-06T23:28:33.693094Z","end":"2026-07-06T23:28:33.79594Z","steps":["trace[810768414] 'range keys from in-memory index tree' (duration: 102.638867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:28:33.79595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.79724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:28:33.796035Z","caller":"traceutil/trace.go:171","msg":"trace[1609716592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8341; }","duration":"144.932533ms","start":"2026-07-06T23:28:33.651083Z","end":"2026-07-06T23:28:33.796015Z","steps":["trace[1609716592] 'range keys from in-memory index tree' (duration: 144.701049ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:28:34.983713Z","caller":"traceutil/trace.go:171","msg":"trace[2064564113] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"122.540366ms","start":"2026-07-06T23:28:34.86115Z","end":"2026-07-06T23:28:34.98369Z","steps":["trace[2064564113] 'process raft request' (duration: 122.401183ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:29:05.262549Z","caller":"traceutil/trace.go:171","msg":"trace[1544575218] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"114.154723ms","start":"2026-07-06T23:29:05.148368Z","end":"2026-07-06T23:29:05.262523Z","steps":["trace[1544575218] 'process raft request' (duration: 113.997059ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:29:14.944651Z","caller":"traceutil/trace.go:171","msg":"trace[1707309980] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"106.680701ms","start":"2026-07-06T23:29:14.837941Z","end":"2026-07-06T23:29:14.944622Z","steps":["trace[1707309980] 'process raft request' (duration: 97.972745ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:29:20.766512Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002346.wal"} {"level":"info","ts":"2026-07-06T23:31:07.866921Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-06T23:31:07.8726Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-06T23:31:07.872706Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-06T23:33:26.757671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8305} {"level":"info","ts":"2026-07-06T23:33:26.836779Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8305,"took":"76.771653ms","hash":2098336342,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":25272320,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-06T23:33:26.836858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2098336342,"revision":8305,"compact-revision":6112} {"level":"warn","ts":"2026-07-06T23:36:07.729095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.557979ms","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-07-06T23:36:07.729244Z","caller":"traceutil/trace.go:171","msg":"trace[1188111168] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:11831; }","duration":"130.729654ms","start":"2026-07-06T23:36:07.598492Z","end":"2026-07-06T23:36:07.729222Z","steps":["trace[1188111168] 'range keys from in-memory index tree' (duration: 130.537529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:36:08.029514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.116293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-07-06T23:36:08.029635Z","caller":"traceutil/trace.go:171","msg":"trace[962433661] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11832; }","duration":"200.250817ms","start":"2026-07-06T23:36:07.829355Z","end":"2026-07-06T23:36:08.029606Z","steps":["trace[962433661] 'range keys from in-memory index tree' (duration: 199.892998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:36:08.029698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.978893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:36:08.029966Z","caller":"traceutil/trace.go:171","msg":"trace[1973353417] transaction","detail":"{read_only:false; response_revision:11833; number_of_response:1; }","duration":"189.365231ms","start":"2026-07-06T23:36:07.840581Z","end":"2026-07-06T23:36:08.029946Z","steps":["trace[1973353417] 'process raft request' (duration: 71.053058ms)","trace[1973353417] 'compare' (duration: 117.84979ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:36:08.03012Z","caller":"traceutil/trace.go:171","msg":"trace[492808107] transaction","detail":"{read_only:false; response_revision:11834; number_of_response:1; }","duration":"182.009271ms","start":"2026-07-06T23:36:07.848059Z","end":"2026-07-06T23:36:08.030068Z","steps":["trace[492808107] 'process raft request' (duration: 181.889808ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:38:26.76346Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10530} {"level":"info","ts":"2026-07-06T23:38:26.834323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10530,"took":"62.724969ms","hash":3394694424,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":26509312,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-06T23:38:26.834375Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3394694424,"revision":10530,"compact-revision":8305} {"level":"info","ts":"2026-07-06T23:42:33.50086Z","caller":"traceutil/trace.go:171","msg":"trace[802043889] transaction","detail":"{read_only:false; response_revision:14415; number_of_response:1; }","duration":"163.173061ms","start":"2026-07-06T23:42:33.33766Z","end":"2026-07-06T23:42:33.500834Z","steps":["trace[802043889] 'process raft request' (duration: 163.082469ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:42:33.501226Z","caller":"traceutil/trace.go:171","msg":"trace[911376915] transaction","detail":"{read_only:false; response_revision:14414; number_of_response:1; }","duration":"166.058373ms","start":"2026-07-06T23:42:33.335131Z","end":"2026-07-06T23:42:33.50119Z","steps":["trace[911376915] 'process raft request' (duration: 165.163881ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:42:49.16059Z","caller":"traceutil/trace.go:171","msg":"trace[989081716] transaction","detail":"{read_only:false; response_revision:14508; number_of_response:1; }","duration":"235.913837ms","start":"2026-07-06T23:42:48.924647Z","end":"2026-07-06T23:42:49.160561Z","steps":["trace[989081716] 'process raft request' (duration: 235.677452ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:42:49.174965Z","caller":"traceutil/trace.go:171","msg":"trace[218656848] linearizableReadLoop","detail":"{readStateIndex:15012; appliedIndex:15011; }","duration":"168.507713ms","start":"2026-07-06T23:42:49.006431Z","end":"2026-07-06T23:42:49.174939Z","steps":["trace[218656848] 'read index received' (duration: 154.773272ms)","trace[218656848] 'applied index is now lower than readState.Index' (duration: 13.733491ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:42:49.175108Z","caller":"traceutil/trace.go:171","msg":"trace[1190249045] transaction","detail":"{read_only:false; response_revision:14509; number_of_response:1; }","duration":"249.955526ms","start":"2026-07-06T23:42:48.925107Z","end":"2026-07-06T23:42:49.175062Z","steps":["trace[1190249045] 'process raft request' (duration: 249.681399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:42:49.175208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.817591ms","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-07-06T23:42:49.175256Z","caller":"traceutil/trace.go:171","msg":"trace[1631842900] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14509; }","duration":"168.941645ms","start":"2026-07-06T23:42:49.006299Z","end":"2026-07-06T23:42:49.175241Z","steps":["trace[1631842900] 'agreement among raft nodes before linearized reading' (duration: 168.73758ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:42:58.717437Z","caller":"traceutil/trace.go:171","msg":"trace[1078691737] transaction","detail":"{read_only:false; response_revision:14677; number_of_response:1; }","duration":"180.722478ms","start":"2026-07-06T23:42:58.536679Z","end":"2026-07-06T23:42:58.717401Z","steps":["trace[1078691737] 'process raft request' (duration: 180.538953ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:42:58.858173Z","caller":"traceutil/trace.go:171","msg":"trace[1255926310] transaction","detail":"{read_only:false; response_revision:14678; number_of_response:1; }","duration":"129.437403ms","start":"2026-07-06T23:42:58.72871Z","end":"2026-07-06T23:42:58.858148Z","steps":["trace[1255926310] 'process raft request' (duration: 129.29125ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:43:26.770218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12788} {"level":"info","ts":"2026-07-06T23:43:26.846408Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12788,"took":"72.823118ms","hash":96808250,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":29552640,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-06T23:43:26.846466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":96808250,"revision":12788,"compact-revision":10530} {"level":"warn","ts":"2026-07-06T23:44:03.816071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.007742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-06T23:44:03.816158Z","caller":"traceutil/trace.go:171","msg":"trace[348382986] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15579; }","duration":"106.139115ms","start":"2026-07-06T23:44:03.710002Z","end":"2026-07-06T23:44:03.816141Z","steps":["trace[348382986] 'range keys from in-memory index tree' (duration: 105.830397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:03.816371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.297313ms","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-07-06T23:44:03.816457Z","caller":"traceutil/trace.go:171","msg":"trace[1851182608] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15579; }","duration":"107.413777ms","start":"2026-07-06T23:44:03.709018Z","end":"2026-07-06T23:44:03.816432Z","steps":["trace[1851182608] 'range keys from in-memory index tree' (duration: 107.225262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:37.463356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.096191ms","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-07-06T23:44:37.463445Z","caller":"traceutil/trace.go:171","msg":"trace[2127375444] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15756; }","duration":"113.233464ms","start":"2026-07-06T23:44:37.350194Z","end":"2026-07-06T23:44:37.463427Z","steps":["trace[2127375444] 'range keys from in-memory index tree' (duration: 113.002058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:37.463682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.814773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-06T23:44:37.463716Z","caller":"traceutil/trace.go:171","msg":"trace[1284448978] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15756; }","duration":"112.882855ms","start":"2026-07-06T23:44:37.350825Z","end":"2026-07-06T23:44:37.463708Z","steps":["trace[1284448978] 'range keys from in-memory index tree' (duration: 112.687219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:37.463871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.29715ms","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-07-06T23:44:37.463904Z","caller":"traceutil/trace.go:171","msg":"trace[1938617815] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15756; }","duration":"112.342671ms","start":"2026-07-06T23:44:37.351553Z","end":"2026-07-06T23:44:37.463895Z","steps":["trace[1938617815] 'range keys from in-memory index tree' (duration: 112.25862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:37.464075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.08216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-06T23:44:37.464107Z","caller":"traceutil/trace.go:171","msg":"trace[1735462417] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15756; }","duration":"111.129091ms","start":"2026-07-06T23:44:37.35297Z","end":"2026-07-06T23:44:37.464099Z","steps":["trace[1735462417] 'range keys from in-memory index tree' (duration: 110.995898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:37.464274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.243984ms","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-07-06T23:44:37.464305Z","caller":"traceutil/trace.go:171","msg":"trace[453156858] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15756; }","duration":"111.297155ms","start":"2026-07-06T23:44:37.353Z","end":"2026-07-06T23:44:37.464298Z","steps":["trace[453156858] 'range keys from in-memory index tree' (duration: 111.149642ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:44:37.622148Z","caller":"traceutil/trace.go:171","msg":"trace[2009272628] transaction","detail":"{read_only:false; response_revision:15757; number_of_response:1; }","duration":"120.752562ms","start":"2026-07-06T23:44:37.501368Z","end":"2026-07-06T23:44:37.622121Z","steps":["trace[2009272628] 'process raft request' (duration: 120.590338ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:44:37.813727Z","caller":"traceutil/trace.go:171","msg":"trace[1929391176] transaction","detail":"{read_only:false; response_revision:15758; number_of_response:1; }","duration":"277.744605ms","start":"2026-07-06T23:44:37.535943Z","end":"2026-07-06T23:44:37.813687Z","steps":["trace[1929391176] 'process raft request' (duration: 277.369266ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:44:37.813972Z","caller":"traceutil/trace.go:171","msg":"trace[1537970982] transaction","detail":"{read_only:false; response_revision:15759; number_of_response:1; }","duration":"270.665968ms","start":"2026-07-06T23:44:37.543295Z","end":"2026-07-06T23:44:37.813961Z","steps":["trace[1537970982] 'process raft request' (duration: 270.149015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:38.027847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.875465ms","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-07-06T23:44:38.027942Z","caller":"traceutil/trace.go:171","msg":"trace[1488632813] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15760; }","duration":"127.025119ms","start":"2026-07-06T23:44:37.900895Z","end":"2026-07-06T23:44:38.027921Z","steps":["trace[1488632813] 'range keys from in-memory index tree' (duration: 126.768813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:38.028384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.878465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-06T23:44:38.028431Z","caller":"traceutil/trace.go:171","msg":"trace[845512776] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15760; }","duration":"126.979579ms","start":"2026-07-06T23:44:37.901441Z","end":"2026-07-06T23:44:38.02842Z","steps":["trace[845512776] 'range keys from in-memory index tree' (duration: 126.65379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:44:38.028857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.984897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-06T23:44:38.02895Z","caller":"traceutil/trace.go:171","msg":"trace[1397953770] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15760; }","duration":"105.11379ms","start":"2026-07-06T23:44:37.923816Z","end":"2026-07-06T23:44:38.028929Z","steps":["trace[1397953770] 'range keys from in-memory index tree' (duration: 104.618336ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:48:26.775748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15056} {"level":"info","ts":"2026-07-06T23:48:26.866402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15056,"took":"87.860861ms","hash":257862390,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-06T23:48:26.866464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":257862390,"revision":15056,"compact-revision":12788} {"level":"warn","ts":"2026-07-06T23:50:24.075349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.308456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-x9xl2\" ","response":"range_response_count:1 size:20738"} {"level":"info","ts":"2026-07-06T23:50:24.075424Z","caller":"traceutil/trace.go:171","msg":"trace[1389507936] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-x9xl2; range_end:; response_count:1; response_revision:18312; }","duration":"207.438079ms","start":"2026-07-06T23:50:23.867969Z","end":"2026-07-06T23:50:24.075407Z","steps":["trace[1389507936] 'range keys from in-memory index tree' (duration: 207.116452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:50:24.075552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.750232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-7xzlb\" ","response":"range_response_count:1 size:22094"} {"level":"warn","ts":"2026-07-06T23:50:24.075577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.437716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-07-06T23:50:24.075648Z","caller":"traceutil/trace.go:171","msg":"trace[282866661] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18312; }","duration":"188.551979ms","start":"2026-07-06T23:50:23.887079Z","end":"2026-07-06T23:50:24.075631Z","steps":["trace[282866661] 'range keys from in-memory index tree' (duration: 188.234001ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:50:24.075648Z","caller":"traceutil/trace.go:171","msg":"trace[590839915] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-7xzlb; range_end:; response_count:1; response_revision:18312; }","duration":"145.924577ms","start":"2026-07-06T23:50:23.929702Z","end":"2026-07-06T23:50:24.075627Z","steps":["trace[590839915] 'range keys from in-memory index tree' (duration: 145.555518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:50:24.075686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.17689ms","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-07-06T23:50:24.07578Z","caller":"traceutil/trace.go:171","msg":"trace[406323532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18312; }","duration":"186.309434ms","start":"2026-07-06T23:50:23.889452Z","end":"2026-07-06T23:50:24.075761Z","steps":["trace[406323532] 'range keys from in-memory index tree' (duration: 186.002246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:50:24.075795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.645291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-07-06T23:50:24.075891Z","caller":"traceutil/trace.go:171","msg":"trace[595465679] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18312; }","duration":"188.802765ms","start":"2026-07-06T23:50:23.887064Z","end":"2026-07-06T23:50:24.075867Z","steps":["trace[595465679] 'range keys from in-memory index tree' (duration: 188.396254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:50:24.44487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.837605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-06T23:50:24.444972Z","caller":"traceutil/trace.go:171","msg":"trace[602041689] linearizableReadLoop","detail":"{readStateIndex:18940; appliedIndex:18939; }","duration":"184.225581ms","start":"2026-07-06T23:50:24.260731Z","end":"2026-07-06T23:50:24.444956Z","steps":["trace[602041689] 'read index received' (duration: 46.401µs)","trace[602041689] 'applied index is now lower than readState.Index' (duration: 184.17811ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:50:24.44506Z","caller":"traceutil/trace.go:171","msg":"trace[328939241] transaction","detail":"{read_only:false; response_revision:18315; number_of_response:1; }","duration":"270.404719ms","start":"2026-07-06T23:50:24.174643Z","end":"2026-07-06T23:50:24.445048Z","steps":["trace[328939241] 'process raft request' (duration: 12.318868ms)","trace[328939241] 'compare' (duration: 257.738843ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:50:24.445569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.818396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-07-06T23:50:24.445651Z","caller":"traceutil/trace.go:171","msg":"trace[1472036601] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18315; }","duration":"184.94973ms","start":"2026-07-06T23:50:24.260682Z","end":"2026-07-06T23:50:24.445631Z","steps":["trace[1472036601] 'agreement among raft nodes before linearized reading' (duration: 184.669653ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:50:24.790321Z","caller":"traceutil/trace.go:171","msg":"trace[7585040] transaction","detail":"{read_only:false; response_revision:18317; number_of_response:1; }","duration":"111.234342ms","start":"2026-07-06T23:50:24.679053Z","end":"2026-07-06T23:50:24.790287Z","steps":["trace[7585040] 'process raft request' (duration: 88.779673ms)","trace[7585040] 'compare' (duration: 22.08207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:50:25.008681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.623742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T23:50:25.008916Z","caller":"traceutil/trace.go:171","msg":"trace[627372784] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18317; }","duration":"107.939919ms","start":"2026-07-06T23:50:24.900953Z","end":"2026-07-06T23:50:25.008893Z","steps":["trace[627372784] 'range keys from in-memory index tree' (duration: 107.426577ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:53:22.101861Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4594e5f716bad646","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-06T23:53:22.107418Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-06T23:53:22.107519Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-06T23:53:26.783404Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17495} {"level":"info","ts":"2026-07-06T23:53:26.885903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17495,"took":"97.221524ms","hash":1666680722,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":30035968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-06T23:53:26.885992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1666680722,"revision":17495,"compact-revision":15056} {"level":"info","ts":"2026-07-06T23:58:26.78927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19355} {"level":"info","ts":"2026-07-06T23:58:26.88392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19355,"took":"92.03032ms","hash":3761826478,"current-db-size-bytes":59695104,"current-db-size":"60 MB","current-db-size-in-use-bytes":24219648,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-06T23:58:26.883994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3761826478,"revision":19355,"compact-revision":17495}