{"level":"warn","ts":"2026-07-08T02:15:25.708825Z","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-08T02:15:25.708976Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-08T02:15:25.709054Z","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-08T02:15:25.709078Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-08T02:15:25.709106Z","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-08T02:15:25.711192Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-07-08T02:15:25.711304Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-08T02:15:25.713818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.211829ms"} {"level":"info","ts":"2026-07-08T02:15:25.71886Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-07-08T02:15:25.718936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T02:15:25.718994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-07-08T02:15:25.719015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T02:15:25.719026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-07-08T02:15:25.719071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-07-08T02:15:25.723291Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T02:15:25.724643Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T02:15:25.725865Z","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-08T02:15:25.728472Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T02:15:25.728663Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T02:15:25.728773Z","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-08T02:15:25.728952Z","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-08T02:15:25.728975Z","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-08T02:15:25.733169Z","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-08T02:15:25.733334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-08T02:15:25.733382Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-08T02:15:25.733534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T02:15:25.733577Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T02:15:25.737324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-07-08T02:15:25.737463Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-08T02:15:26.219661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T02:15:26.219742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T02:15:26.219782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-07-08T02:15:26.219802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-07-08T02:15:26.219818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-08T02:15:26.219839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-07-08T02:15:26.219856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-08T02:15:26.221103Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:26.222092Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:15:26.222083Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T02:15:26.222141Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:15:26.222454Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T02:15:26.222519Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T02:15:26.224511Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T02:15:26.225381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-07-08T02:15:26.236764Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:26.237014Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:26.237073Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:42.034011Z","caller":"traceutil/trace.go:171","msg":"trace[1537271073] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"123.380303ms","start":"2026-07-08T02:15:41.910606Z","end":"2026-07-08T02:15:42.033986Z","steps":["trace[1537271073] 'process raft request' (duration: 123.220139ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:15:42.151426Z","caller":"traceutil/trace.go:171","msg":"trace[827100630] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"104.91361ms","start":"2026-07-08T02:15:42.046475Z","end":"2026-07-08T02:15:42.151389Z","steps":["trace[827100630] 'process raft request' (duration: 103.761459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:10.336292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.785481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:16:10.336444Z","caller":"traceutil/trace.go:171","msg":"trace[821451855] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"116.631734ms","start":"2026-07-08T02:16:10.219795Z","end":"2026-07-08T02:16:10.336427Z","steps":["trace[821451855] 'read index received' (duration: 68.352µs)","trace[821451855] 'applied index is now lower than readState.Index' (duration: 116.562052ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:16:10.336538Z","caller":"traceutil/trace.go:171","msg":"trace[1240468759] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"364.448707ms","start":"2026-07-08T02:16:09.972026Z","end":"2026-07-08T02:16:10.336475Z","steps":["trace[1240468759] 'process raft request' (duration: 181.841823ms)","trace[1240468759] 'compare' (duration: 181.36887ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:16:10.336654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.86051ms","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-07-08T02:16:10.336685Z","caller":"traceutil/trace.go:171","msg":"trace[577825093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"116.929492ms","start":"2026-07-08T02:16:10.219747Z","end":"2026-07-08T02:16:10.336676Z","steps":["trace[577825093] 'agreement among raft nodes before linearized reading' (duration: 116.775808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:10.336771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:09.971977Z","time spent":"364.666332ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:16:10.337266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.135992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n2t48\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-08T02:16:10.337336Z","caller":"traceutil/trace.go:171","msg":"trace[1772476035] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n2t48; range_end:; response_count:1; response_revision:589; }","duration":"105.234804ms","start":"2026-07-08T02:16:10.232082Z","end":"2026-07-08T02:16:10.337317Z","steps":["trace[1772476035] 'agreement among raft nodes before linearized reading' (duration: 105.026559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:10.709761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.971662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:16:10.709844Z","caller":"traceutil/trace.go:171","msg":"trace[712490574] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:593; }","duration":"267.112817ms","start":"2026-07-08T02:16:10.442712Z","end":"2026-07-08T02:16:10.709825Z","steps":["trace[712490574] 'range keys from in-memory index tree' (duration: 266.859289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:10.709761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.579417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:16:10.710091Z","caller":"traceutil/trace.go:171","msg":"trace[947511205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"212.916136ms","start":"2026-07-08T02:16:10.497147Z","end":"2026-07-08T02:16:10.710063Z","steps":["trace[947511205] 'range keys from in-memory index tree' (duration: 212.437662ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:16:25.644262Z","caller":"traceutil/trace.go:171","msg":"trace[1369088549] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"306.105704ms","start":"2026-07-08T02:16:25.338133Z","end":"2026-07-08T02:16:25.644238Z","steps":["trace[1369088549] 'process raft request' (duration: 305.9606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:25.644416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:25.338108Z","time spent":"306.236007ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:16:25.808551Z","caller":"traceutil/trace.go:171","msg":"trace[402779767] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"309.314941ms","start":"2026-07-08T02:16:25.499206Z","end":"2026-07-08T02:16:25.808521Z","steps":["trace[402779767] 'read index received' (duration: 144.918301ms)","trace[402779767] 'applied index is now lower than readState.Index' (duration: 164.39509ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:16:25.808695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.469856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:16:25.808732Z","caller":"traceutil/trace.go:171","msg":"trace[291790164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"309.555867ms","start":"2026-07-08T02:16:25.499166Z","end":"2026-07-08T02:16:25.808722Z","steps":["trace[291790164] 'agreement among raft nodes before linearized reading' (duration: 309.466955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:25.808772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:25.499149Z","time spent":"309.616289ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T02:16:25.808695Z","caller":"traceutil/trace.go:171","msg":"trace[357943100] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"467.42547ms","start":"2026-07-08T02:16:25.341192Z","end":"2026-07-08T02:16:25.808618Z","steps":["trace[357943100] 'process raft request' (duration: 452.207527ms)","trace[357943100] 'compare' (duration: 14.897525ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:16:25.808945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:25.341176Z","time spent":"467.673717ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:16:43.60928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.936918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:16:43.609382Z","caller":"traceutil/trace.go:171","msg":"trace[1188330670] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"473.151515ms","start":"2026-07-08T02:16:43.136214Z","end":"2026-07-08T02:16:43.609366Z","steps":["trace[1188330670] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/replicasets/cert-manager/cert-manager-webhook-7d6b59cfdc; req_size:4558; } (duration: 468.459768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:43.609468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:43.136199Z","time spent":"473.247837ms","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4561,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T02:16:43.66812Z","caller":"traceutil/trace.go:171","msg":"trace[922258479] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"531.784995ms","start":"2026-07-08T02:16:43.136283Z","end":"2026-07-08T02:16:43.668068Z","steps":["trace[922258479] 'process raft request' (duration: 473.074413ms)","trace[922258479] 'compare' (duration: 58.403564ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:16:43.668299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:43.136276Z","time spent":"531.945959ms","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3494,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T02:16:55.760663Z","caller":"traceutil/trace.go:171","msg":"trace[1407870883] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"269.149139ms","start":"2026-07-08T02:16:55.491498Z","end":"2026-07-08T02:16:55.760647Z","steps":["trace[1407870883] 'process raft request' (duration: 268.997585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:17:42.199482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.07624ms","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-08T02:17:42.199594Z","caller":"traceutil/trace.go:171","msg":"trace[1593720144] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1823; }","duration":"129.229323ms","start":"2026-07-08T02:17:42.070346Z","end":"2026-07-08T02:17:42.199576Z","steps":["trace[1593720144] 'range keys from in-memory index tree' (duration: 128.883674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:27.458334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.705194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:18:27.458529Z","caller":"traceutil/trace.go:171","msg":"trace[1952475323] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"201.034649ms","start":"2026-07-08T02:18:27.257477Z","end":"2026-07-08T02:18:27.458512Z","steps":["trace[1952475323] 'process raft request' (duration: 200.963487ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:27.458532Z","caller":"traceutil/trace.go:171","msg":"trace[275888258] linearizableReadLoop","detail":"{readStateIndex:2208; appliedIndex:2207; }","duration":"246.324377ms","start":"2026-07-08T02:18:27.212197Z","end":"2026-07-08T02:18:27.458522Z","steps":["trace[275888258] 'read index received' (duration: 61.962µs)","trace[275888258] 'applied index is now lower than readState.Index' (duration: 246.259985ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:18:27.458582Z","caller":"traceutil/trace.go:171","msg":"trace[1136307548] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"406.857721ms","start":"2026-07-08T02:18:27.05168Z","end":"2026-07-08T02:18:27.458538Z","steps":["trace[1136307548] 'process raft request' (duration: 50.87381ms)","trace[1136307548] 'compare' (duration: 355.605721ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:18:27.458715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.515782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-08T02:18:27.458722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.667454ms","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-07-08T02:18:27.458749Z","caller":"traceutil/trace.go:171","msg":"trace[1807893959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2122; }","duration":"246.593134ms","start":"2026-07-08T02:18:27.212146Z","end":"2026-07-08T02:18:27.45874Z","steps":["trace[1807893959] 'agreement among raft nodes before linearized reading' (duration: 246.488251ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:27.458769Z","caller":"traceutil/trace.go:171","msg":"trace[1133918519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2122; }","duration":"244.062115ms","start":"2026-07-08T02:18:27.214699Z","end":"2026-07-08T02:18:27.458761Z","steps":["trace[1133918519] 'agreement among raft nodes before linearized reading' (duration: 243.946312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:27.458783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:18:27.051659Z","time spent":"407.021525ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:18:27.722738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.277153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:18:27.723232Z","caller":"traceutil/trace.go:171","msg":"trace[1042604018] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"259.888338ms","start":"2026-07-08T02:18:27.463305Z","end":"2026-07-08T02:18:27.723193Z","steps":["trace[1042604018] 'process raft request' (duration: 105.056729ms)","trace[1042604018] 'compare' (duration: 154.16445ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:18:27.723308Z","caller":"traceutil/trace.go:171","msg":"trace[1904477096] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"259.432585ms","start":"2026-07-08T02:18:27.46386Z","end":"2026-07-08T02:18:27.723293Z","steps":["trace[1904477096] 'process raft request' (duration: 259.25383ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:27.723251Z","caller":"traceutil/trace.go:171","msg":"trace[335811511] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2209; }","duration":"226.476515ms","start":"2026-07-08T02:18:27.496755Z","end":"2026-07-08T02:18:27.723232Z","steps":["trace[335811511] 'read index received' (duration: 6.432555ms)","trace[335811511] 'applied index is now lower than readState.Index' (duration: 220.04301ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:18:27.723519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.739192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:18:27.723678Z","caller":"traceutil/trace.go:171","msg":"trace[430454305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2125; }","duration":"226.944497ms","start":"2026-07-08T02:18:27.496713Z","end":"2026-07-08T02:18:27.723657Z","steps":["trace[430454305] 'agreement among raft nodes before linearized reading' (duration: 226.67703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:27.723724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.474062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129227"} {"level":"warn","ts":"2026-07-08T02:18:27.723702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.561124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-08T02:18:27.723763Z","caller":"traceutil/trace.go:171","msg":"trace[948267148] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2125; }","duration":"179.562294ms","start":"2026-07-08T02:18:27.544188Z","end":"2026-07-08T02:18:27.72375Z","steps":["trace[948267148] 'agreement among raft nodes before linearized reading' (duration: 179.392079ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:27.723803Z","caller":"traceutil/trace.go:171","msg":"trace[722155329] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2125; }","duration":"179.690437ms","start":"2026-07-08T02:18:27.544085Z","end":"2026-07-08T02:18:27.723776Z","steps":["trace[722155329] 'agreement among raft nodes before linearized reading' (duration: 179.525183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:27.724458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.613058ms","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-08T02:18:27.724525Z","caller":"traceutil/trace.go:171","msg":"trace[261459018] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2125; }","duration":"189.70729ms","start":"2026-07-08T02:18:27.534798Z","end":"2026-07-08T02:18:27.724505Z","steps":["trace[261459018] 'agreement among raft nodes before linearized reading' (duration: 188.745204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:34.10529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.622333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:18:34.105549Z","caller":"traceutil/trace.go:171","msg":"trace[674091644] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"271.115254ms","start":"2026-07-08T02:18:33.834401Z","end":"2026-07-08T02:18:34.105516Z","steps":["trace[674091644] 'process raft request' (duration: 120.198773ms)","trace[674091644] 'compare' (duration: 150.51367ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:18:34.106016Z","caller":"traceutil/trace.go:171","msg":"trace[1997841111] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"270.800975ms","start":"2026-07-08T02:18:33.835199Z","end":"2026-07-08T02:18:34.106Z","steps":["trace[1997841111] 'process raft request' (duration: 270.270231ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:34.16627Z","caller":"traceutil/trace.go:171","msg":"trace[584150248] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"105.345805ms","start":"2026-07-08T02:18:34.060889Z","end":"2026-07-08T02:18:34.166235Z","steps":["trace[584150248] 'process raft request' (duration: 105.093738ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:39.509912Z","caller":"traceutil/trace.go:171","msg":"trace[446339633] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"159.352842ms","start":"2026-07-08T02:18:39.350537Z","end":"2026-07-08T02:18:39.509889Z","steps":["trace[446339633] 'process raft request' (duration: 72.623814ms)","trace[446339633] 'compare' (duration: 86.615825ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:18:39.655447Z","caller":"traceutil/trace.go:171","msg":"trace[1892216815] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"103.850926ms","start":"2026-07-08T02:18:39.551561Z","end":"2026-07-08T02:18:39.655412Z","steps":["trace[1892216815] 'process raft request' (duration: 94.183932ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:19:37.170401Z","caller":"traceutil/trace.go:171","msg":"trace[1532333480] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"168.145723ms","start":"2026-07-08T02:19:37.002228Z","end":"2026-07-08T02:19:37.170374Z","steps":["trace[1532333480] 'process raft request' (duration: 167.963798ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:20:10.667571Z","caller":"traceutil/trace.go:171","msg":"trace[1837243278] linearizableReadLoop","detail":"{readStateIndex:2988; appliedIndex:2987; }","duration":"108.522908ms","start":"2026-07-08T02:20:10.559018Z","end":"2026-07-08T02:20:10.667541Z","steps":["trace[1837243278] 'read index received' (duration: 91.680766ms)","trace[1837243278] 'applied index is now lower than readState.Index' (duration: 16.840932ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:20:10.667729Z","caller":"traceutil/trace.go:171","msg":"trace[50497917] transaction","detail":"{read_only:false; response_revision:2871; number_of_response:1; }","duration":"163.199148ms","start":"2026-07-08T02:20:10.504517Z","end":"2026-07-08T02:20:10.667716Z","steps":["trace[50497917] 'process raft request' (duration: 146.255023ms)","trace[50497917] 'compare' (duration: 16.537353ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:20:10.667963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.004141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:20:10.668095Z","caller":"traceutil/trace.go:171","msg":"trace[1107419243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2871; }","duration":"109.178576ms","start":"2026-07-08T02:20:10.558834Z","end":"2026-07-08T02:20:10.668013Z","steps":["trace[1107419243] 'agreement among raft nodes before linearized reading' (duration: 109.084423ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:20:11.154414Z","caller":"traceutil/trace.go:171","msg":"trace[1270720094] transaction","detail":"{read_only:false; response_revision:2874; number_of_response:1; }","duration":"176.541853ms","start":"2026-07-08T02:20:10.977846Z","end":"2026-07-08T02:20:11.154388Z","steps":["trace[1270720094] 'process raft request' (duration: 176.390249ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:15.169845Z","caller":"traceutil/trace.go:171","msg":"trace[1080557488] linearizableReadLoop","detail":"{readStateIndex:5140; appliedIndex:5139; }","duration":"301.186813ms","start":"2026-07-08T02:24:14.868624Z","end":"2026-07-08T02:24:15.169811Z","steps":["trace[1080557488] 'read index received' (duration: 300.987297ms)","trace[1080557488] 'applied index is now lower than readState.Index' (duration: 198.306µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:15.170619Z","caller":"traceutil/trace.go:171","msg":"trace[498843618] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"324.235906ms","start":"2026-07-08T02:24:14.846362Z","end":"2026-07-08T02:24:15.170598Z","steps":["trace[498843618] 'process raft request' (duration: 323.2837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:15.170848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.574193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-25cdb0ee-cd21-48a5-8aba-88efaa58dd8c\" ","response":"range_response_count:1 size:4081"} {"level":"info","ts":"2026-07-08T02:24:15.170925Z","caller":"traceutil/trace.go:171","msg":"trace[1233094217] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-25cdb0ee-cd21-48a5-8aba-88efaa58dd8c; range_end:; response_count:1; response_revision:4944; }","duration":"171.704977ms","start":"2026-07-08T02:24:14.999198Z","end":"2026-07-08T02:24:15.170903Z","steps":["trace[1233094217] 'agreement among raft nodes before linearized reading' (duration: 171.46246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:15.170999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:14.846343Z","time spent":"324.34664ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:24:15.171086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.432738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531213"} {"level":"info","ts":"2026-07-08T02:24:15.171164Z","caller":"traceutil/trace.go:171","msg":"trace[1449279483] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4944; }","duration":"302.55556ms","start":"2026-07-08T02:24:14.868588Z","end":"2026-07-08T02:24:15.171143Z","steps":["trace[1449279483] 'agreement among raft nodes before linearized reading' (duration: 302.106248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:15.171202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:14.86857Z","time spent":"302.622972ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":23,"response size":531237,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-07-08T02:24:16.113174Z","caller":"traceutil/trace.go:171","msg":"trace[624620505] linearizableReadLoop","detail":"{readStateIndex:5162; appliedIndex:5161; }","duration":"149.043154ms","start":"2026-07-08T02:24:15.96411Z","end":"2026-07-08T02:24:16.113153Z","steps":["trace[624620505] 'read index received' (duration: 148.840998ms)","trace[624620505] 'applied index is now lower than readState.Index' (duration: 201.515µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:16.113208Z","caller":"traceutil/trace.go:171","msg":"trace[1665593441] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"154.063422ms","start":"2026-07-08T02:24:15.959131Z","end":"2026-07-08T02:24:16.113194Z","steps":["trace[1665593441] 'process raft request' (duration: 153.891027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:16.113422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.29148ms","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-08T02:24:16.113459Z","caller":"traceutil/trace.go:171","msg":"trace[2945931] 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:4966; }","duration":"149.360273ms","start":"2026-07-08T02:24:15.964088Z","end":"2026-07-08T02:24:16.113448Z","steps":["trace[2945931] 'agreement among raft nodes before linearized reading' (duration: 149.198228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.010864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317065859777269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-08T02:24:17.032174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.508075Z","time spent":"524.091835ms","remote":"127.0.0.1:57186","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-08T02:24:17.032265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.77794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8dlcx\" ","response":"range_response_count:1 size:5144"} {"level":"info","ts":"2026-07-08T02:24:17.032343Z","caller":"traceutil/trace.go:171","msg":"trace[752555590] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8dlcx; range_end:; response_count:1; response_revision:4968; }","duration":"522.894873ms","start":"2026-07-08T02:24:16.509425Z","end":"2026-07-08T02:24:17.03232Z","steps":["trace[752555590] 'range keys from in-memory index tree' (duration: 522.632676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.032379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.973755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T02:24:17.032401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.509411Z","time spent":"522.972125ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5168,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8dlcx\" "} {"level":"info","ts":"2026-07-08T02:24:17.032463Z","caller":"traceutil/trace.go:171","msg":"trace[764185930] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4968; }","duration":"523.076388ms","start":"2026-07-08T02:24:16.509355Z","end":"2026-07-08T02:24:17.032432Z","steps":["trace[764185930] 'range keys from in-memory index tree' (duration: 522.820021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.03251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.509342Z","time spent":"523.157571ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T02:24:17.041672Z","caller":"traceutil/trace.go:171","msg":"trace[77048225] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"532.097446ms","start":"2026-07-08T02:24:16.509561Z","end":"2026-07-08T02:24:17.041659Z","steps":["trace[77048225] 'process raft request' (duration: 532.005134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.041759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.509554Z","time spent":"532.156968ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14481,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:24:17.046014Z","caller":"traceutil/trace.go:171","msg":"trace[555084586] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"221.702009ms","start":"2026-07-08T02:24:16.824298Z","end":"2026-07-08T02:24:17.046Z","steps":["trace[555084586] 'process raft request' (duration: 221.598076ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.046173Z","caller":"traceutil/trace.go:171","msg":"trace[164091116] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5164; }","duration":"535.90991ms","start":"2026-07-08T02:24:16.510248Z","end":"2026-07-08T02:24:17.046158Z","steps":["trace[164091116] 'read index received' (duration: 531.333765ms)","trace[164091116] 'applied index is now lower than readState.Index' (duration: 4.575265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:24:17.046334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.079165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:17.046375Z","caller":"traceutil/trace.go:171","msg":"trace[1478034157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4972; }","duration":"536.149477ms","start":"2026-07-08T02:24:16.510215Z","end":"2026-07-08T02:24:17.046364Z","steps":["trace[1478034157] 'agreement among raft nodes before linearized reading' (duration: 536.083485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.046405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.510201Z","time spent":"536.199698ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T02:24:17.46931Z","caller":"traceutil/trace.go:171","msg":"trace[1234192227] linearizableReadLoop","detail":"{readStateIndex:5169; appliedIndex:5168; }","duration":"423.067971ms","start":"2026-07-08T02:24:17.046222Z","end":"2026-07-08T02:24:17.46929Z","steps":["trace[1234192227] 'read index received' (duration: 422.376572ms)","trace[1234192227] 'applied index is now lower than readState.Index' (duration: 690.579µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:17.469377Z","caller":"traceutil/trace.go:171","msg":"trace[988782163] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"424.328846ms","start":"2026-07-08T02:24:17.045008Z","end":"2026-07-08T02:24:17.469337Z","steps":["trace[988782163] 'process raft request' (duration: 423.584395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.46944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.865013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-trq75.18c02f4bf551a444\" ","response":"range_response_count:1 size:962"} {"level":"warn","ts":"2026-07-08T02:24:17.469464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.883688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5wq4w\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-08T02:24:17.469474Z","caller":"traceutil/trace.go:171","msg":"trace[525494740] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-trq75.18c02f4bf551a444; range_end:; response_count:1; response_revision:4973; }","duration":"735.943265ms","start":"2026-07-08T02:24:16.733521Z","end":"2026-07-08T02:24:17.469464Z","steps":["trace[525494740] 'agreement among raft nodes before linearized reading' (duration: 735.786371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.46948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.452957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-07-08T02:24:17.469497Z","caller":"traceutil/trace.go:171","msg":"trace[281105379] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5wq4w; range_end:; response_count:1; response_revision:4973; }","duration":"915.933469ms","start":"2026-07-08T02:24:16.553552Z","end":"2026-07-08T02:24:17.469485Z","steps":["trace[281105379] 'agreement among raft nodes before linearized reading' (duration: 915.827196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.469502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.733496Z","time spent":"735.998067ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":986,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-trq75.18c02f4bf551a444\" "} {"level":"warn","ts":"2026-07-08T02:24:17.469497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.044985Z","time spent":"424.45349ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T02:24:17.469513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.553542Z","time spent":"915.96679ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-5wq4w\" "} {"level":"info","ts":"2026-07-08T02:24:17.469501Z","caller":"traceutil/trace.go:171","msg":"trace[1751383588] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4973; }","duration":"958.488988ms","start":"2026-07-08T02:24:16.511005Z","end":"2026-07-08T02:24:17.469494Z","steps":["trace[1751383588] 'agreement among raft nodes before linearized reading' (duration: 958.411656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.469559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.842833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-08T02:24:17.469624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.510999Z","time spent":"958.599741ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1253,"request content":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-07-08T02:24:17.469625Z","caller":"traceutil/trace.go:171","msg":"trace[607974862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4973; }","duration":"932.948396ms","start":"2026-07-08T02:24:16.536658Z","end":"2026-07-08T02:24:17.469606Z","steps":["trace[607974862] 'agreement among raft nodes before linearized reading' (duration: 932.758161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.46966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.536637Z","time spent":"933.014518ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T02:24:17.469706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.072649ms","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-07-08T02:24:17.469729Z","caller":"traceutil/trace.go:171","msg":"trace[326172296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4973; }","duration":"732.12512ms","start":"2026-07-08T02:24:16.737597Z","end":"2026-07-08T02:24:17.469722Z","steps":["trace[326172296] 'agreement among raft nodes before linearized reading' (duration: 732.007587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.469748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.737583Z","time spent":"732.161091ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T02:24:17.470775Z","caller":"traceutil/trace.go:171","msg":"trace[253567575] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"418.6599ms","start":"2026-07-08T02:24:17.052104Z","end":"2026-07-08T02:24:17.470764Z","steps":["trace[253567575] 'process raft request' (duration: 418.531116ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.470808Z","caller":"traceutil/trace.go:171","msg":"trace[2026631064] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"420.942213ms","start":"2026-07-08T02:24:17.049854Z","end":"2026-07-08T02:24:17.470796Z","steps":["trace[2026631064] 'process raft request' (duration: 420.727827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.470844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.052097Z","time spent":"418.702971ms","remote":"127.0.0.1:42878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1852,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:24:17.47086Z","caller":"traceutil/trace.go:171","msg":"trace[1487580006] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"417.397595ms","start":"2026-07-08T02:24:17.053453Z","end":"2026-07-08T02:24:17.47085Z","steps":["trace[1487580006] 'process raft request' (duration: 417.21182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.470908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.053446Z","time spent":"417.429676ms","remote":"127.0.0.1:43064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:24:17.47118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.873521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:17.471217Z","caller":"traceutil/trace.go:171","msg":"trace[2069342394] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4978; }","duration":"341.930302ms","start":"2026-07-08T02:24:17.129272Z","end":"2026-07-08T02:24:17.471203Z","steps":["trace[2069342394] 'agreement among raft nodes before linearized reading' (duration: 341.881971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.129263Z","time spent":"341.977044ms","remote":"127.0.0.1:54102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-08T02:24:17.471253Z","caller":"traceutil/trace.go:171","msg":"trace[931965075] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"295.97821ms","start":"2026-07-08T02:24:17.17526Z","end":"2026-07-08T02:24:17.471239Z","steps":["trace[931965075] 'process raft request' (duration: 295.462366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.227656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:17.471321Z","caller":"traceutil/trace.go:171","msg":"trace[1889590366] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4978; }","duration":"419.324738ms","start":"2026-07-08T02:24:17.05198Z","end":"2026-07-08T02:24:17.471304Z","steps":["trace[1889590366] 'agreement among raft nodes before linearized reading' (duration: 419.194254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.941125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"warn","ts":"2026-07-08T02:24:17.471351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.051972Z","time spent":"419.37162ms","remote":"127.0.0.1:54182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-08T02:24:17.471345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.143141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:17.471385Z","caller":"traceutil/trace.go:171","msg":"trace[1905675937] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4978; }","duration":"345.014977ms","start":"2026-07-08T02:24:17.126361Z","end":"2026-07-08T02:24:17.471376Z","steps":["trace[1905675937] 'agreement among raft nodes before linearized reading' (duration: 344.944185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.47138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.37026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-trq75\" ","response":"range_response_count:1 size:9867"} {"level":"warn","ts":"2026-07-08T02:24:17.471402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.126339Z","time spent":"345.058958ms","remote":"127.0.0.1:42776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-07-08T02:24:17.471404Z","caller":"traceutil/trace.go:171","msg":"trace[1239180509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4978; }","duration":"424.251264ms","start":"2026-07-08T02:24:17.047138Z","end":"2026-07-08T02:24:17.471389Z","steps":["trace[1239180509] 'agreement among raft nodes before linearized reading' (duration: 424.127041ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.471429Z","caller":"traceutil/trace.go:171","msg":"trace[1614648488] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-trq75; range_end:; response_count:1; response_revision:4978; }","duration":"423.444322ms","start":"2026-07-08T02:24:17.047969Z","end":"2026-07-08T02:24:17.471414Z","steps":["trace[1614648488] 'agreement among raft nodes before linearized reading' (duration: 423.213976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.047129Z","time spent":"424.302685ms","remote":"127.0.0.1:42604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T02:24:17.47146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.047933Z","time spent":"423.518554ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9891,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-trq75\" "} {"level":"warn","ts":"2026-07-08T02:24:17.471122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.049844Z","time spent":"421.248711ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1248,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:24:17.47143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.901332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-07-08T02:24:17.471538Z","caller":"traceutil/trace.go:171","msg":"trace[510416567] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4978; }","duration":"259.036015ms","start":"2026-07-08T02:24:17.212494Z","end":"2026-07-08T02:24:17.47153Z","steps":["trace[510416567] 'agreement among raft nodes before linearized reading' (duration: 258.716996ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.984054Z","caller":"traceutil/trace.go:171","msg":"trace[936555178] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"109.083646ms","start":"2026-07-08T02:24:17.874931Z","end":"2026-07-08T02:24:17.984014Z","steps":["trace[936555178] 'process raft request' (duration: 103.39501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:20.468955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.693748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-07-08T02:24:20.469151Z","caller":"traceutil/trace.go:171","msg":"trace[1282547579] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"110.335682ms","start":"2026-07-08T02:24:20.358801Z","end":"2026-07-08T02:24:20.469137Z","steps":["trace[1282547579] 'process raft request' (duration: 110.25196ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:20.469267Z","caller":"traceutil/trace.go:171","msg":"trace[377378499] linearizableReadLoop","detail":"{readStateIndex:5315; appliedIndex:5314; }","duration":"181.400735ms","start":"2026-07-08T02:24:20.287844Z","end":"2026-07-08T02:24:20.469244Z","steps":["trace[377378499] 'read index received' (duration: 5.263625ms)","trace[377378499] 'applied index is now lower than readState.Index' (duration: 176.13501ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:20.469289Z","caller":"traceutil/trace.go:171","msg":"trace[1606665586] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5115; }","duration":"224.90218ms","start":"2026-07-08T02:24:20.244352Z","end":"2026-07-08T02:24:20.469254Z","steps":["trace[1606665586] 'process raft request' (duration: 48.822922ms)","trace[1606665586] 'compare' (duration: 175.644986ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:24:20.469464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.602731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18c02f4cc5d934a5\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-07-08T02:24:20.469519Z","caller":"traceutil/trace.go:171","msg":"trace[2018226897] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18c02f4cc5d934a5; range_end:; response_count:1; response_revision:5116; }","duration":"181.700033ms","start":"2026-07-08T02:24:20.287804Z","end":"2026-07-08T02:24:20.469504Z","steps":["trace[2018226897] 'agreement among raft nodes before linearized reading' (duration: 181.511388ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:34.064073Z","caller":"traceutil/trace.go:171","msg":"trace[932330126] linearizableReadLoop","detail":"{readStateIndex:5573; appliedIndex:5572; }","duration":"200.207781ms","start":"2026-07-08T02:24:33.863845Z","end":"2026-07-08T02:24:34.064052Z","steps":["trace[932330126] 'read index received' (duration: 200.071508ms)","trace[932330126] 'applied index is now lower than readState.Index' (duration: 135.383µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:34.064119Z","caller":"traceutil/trace.go:171","msg":"trace[1447242452] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"215.77857ms","start":"2026-07-08T02:24:33.848291Z","end":"2026-07-08T02:24:34.06407Z","steps":["trace[1447242452] 'process raft request' (duration: 215.569144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:34.064345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.48816ms","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-07-08T02:24:34.064407Z","caller":"traceutil/trace.go:171","msg":"trace[1138082553] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5366; }","duration":"200.602673ms","start":"2026-07-08T02:24:33.863791Z","end":"2026-07-08T02:24:34.064394Z","steps":["trace[1138082553] 'agreement among raft nodes before linearized reading' (duration: 200.367916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:34.073931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.880651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-nqsj2\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-07-08T02:24:34.074271Z","caller":"traceutil/trace.go:171","msg":"trace[2030963105] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-nqsj2; range_end:; response_count:1; response_revision:5367; }","duration":"181.267382ms","start":"2026-07-08T02:24:33.892986Z","end":"2026-07-08T02:24:34.074253Z","steps":["trace[2030963105] 'agreement among raft nodes before linearized reading' (duration: 180.757888ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:34.31009Z","caller":"traceutil/trace.go:171","msg":"trace[1562567448] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"105.947582ms","start":"2026-07-08T02:24:34.204113Z","end":"2026-07-08T02:24:34.310061Z","steps":["trace[1562567448] 'process raft request' (duration: 63.705631ms)","trace[1562567448] 'compare' (duration: 41.881521ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:34.310295Z","caller":"traceutil/trace.go:171","msg":"trace[131795383] linearizableReadLoop","detail":"{readStateIndex:5585; appliedIndex:5584; }","duration":"104.855921ms","start":"2026-07-08T02:24:34.205419Z","end":"2026-07-08T02:24:34.310275Z","steps":["trace[131795383] 'read index received' (duration: 9.899062ms)","trace[131795383] 'applied index is now lower than readState.Index' (duration: 94.955639ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:34.310355Z","caller":"traceutil/trace.go:171","msg":"trace[524932451] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"104.0929ms","start":"2026-07-08T02:24:34.206249Z","end":"2026-07-08T02:24:34.310342Z","steps":["trace[524932451] 'process raft request' (duration: 103.7053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:34.310518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.068807ms","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-08T02:24:34.310741Z","caller":"traceutil/trace.go:171","msg":"trace[2035790527] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5379; }","duration":"105.144059ms","start":"2026-07-08T02:24:34.205401Z","end":"2026-07-08T02:24:34.310546Z","steps":["trace[2035790527] 'agreement among raft nodes before linearized reading' (duration: 105.001625ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:25:26.254154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2957} {"level":"info","ts":"2026-07-08T02:25:26.321463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2957,"took":"66.24245ms","hash":3847251184,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":33660928,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-08T02:25:26.321535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3847251184,"revision":2957,"compact-revision":-1} {"level":"info","ts":"2026-07-08T02:30:26.26045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5996} {"level":"info","ts":"2026-07-08T02:30:26.359201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5996,"took":"95.467992ms","hash":2800730929,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":21794816,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-08T02:30:26.359295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2800730929,"revision":5996,"compact-revision":2957} {"level":"info","ts":"2026-07-08T02:30:56.834447Z","caller":"traceutil/trace.go:171","msg":"trace[1910450321] linearizableReadLoop","detail":"{readStateIndex:8716; appliedIndex:8715; }","duration":"118.074581ms","start":"2026-07-08T02:30:56.716344Z","end":"2026-07-08T02:30:56.834419Z","steps":["trace[1910450321] 'read index received' (duration: 117.799144ms)","trace[1910450321] 'applied index is now lower than readState.Index' (duration: 274.297µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:30:56.834725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.35779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7749f48475-tfd7l.18c02fa6fe25def1\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-07-08T02:30:56.83477Z","caller":"traceutil/trace.go:171","msg":"trace[341265546] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7749f48475-tfd7l.18c02fa6fe25def1; range_end:; response_count:1; response_revision:8413; }","duration":"118.495014ms","start":"2026-07-08T02:30:56.71626Z","end":"2026-07-08T02:30:56.834755Z","steps":["trace[341265546] 'agreement among raft nodes before linearized reading' (duration: 118.279938ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:30:56.835177Z","caller":"traceutil/trace.go:171","msg":"trace[275521488] transaction","detail":"{read_only:false; response_revision:8413; number_of_response:1; }","duration":"146.550191ms","start":"2026-07-08T02:30:56.688601Z","end":"2026-07-08T02:30:56.835151Z","steps":["trace[275521488] 'process raft request' (duration: 145.635405ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:30:56.996313Z","caller":"traceutil/trace.go:171","msg":"trace[1770961976] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"159.034866ms","start":"2026-07-08T02:30:56.837248Z","end":"2026-07-08T02:30:56.996283Z","steps":["trace[1770961976] 'process raft request' (duration: 117.533167ms)","trace[1770961976] 'compare' (duration: 41.366766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:08.653824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.827551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:31:08.653946Z","caller":"traceutil/trace.go:171","msg":"trace[1685132205] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8779; }","duration":"155.713744ms","start":"2026-07-08T02:31:08.498214Z","end":"2026-07-08T02:31:08.653928Z","steps":["trace[1685132205] 'read index received' (duration: 59.771µs)","trace[1685132205] 'applied index is now lower than readState.Index' (duration: 155.652563ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:08.653994Z","caller":"traceutil/trace.go:171","msg":"trace[2020700705] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"250.760249ms","start":"2026-07-08T02:31:08.40318Z","end":"2026-07-08T02:31:08.65394Z","steps":["trace[2020700705] 'process raft request' (duration: 78.704261ms)","trace[2020700705] 'compare' (duration: 171.662557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:08.654154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.945521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:08.654243Z","caller":"traceutil/trace.go:171","msg":"trace[162418947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8475; }","duration":"156.071395ms","start":"2026-07-08T02:31:08.498153Z","end":"2026-07-08T02:31:08.654225Z","steps":["trace[162418947] 'agreement among raft nodes before linearized reading' (duration: 155.882559ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:08.72025Z","caller":"traceutil/trace.go:171","msg":"trace[860404235] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"213.070124ms","start":"2026-07-08T02:31:08.507128Z","end":"2026-07-08T02:31:08.720198Z","steps":["trace[860404235] 'process raft request' (duration: 212.787216ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:09.421749Z","caller":"traceutil/trace.go:171","msg":"trace[572207324] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"146.927071ms","start":"2026-07-08T02:31:09.274791Z","end":"2026-07-08T02:31:09.421718Z","steps":["trace[572207324] 'process raft request' (duration: 132.207773ms)","trace[572207324] 'compare' (duration: 14.507742ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:10.024488Z","caller":"traceutil/trace.go:171","msg":"trace[1642767148] linearizableReadLoop","detail":"{readStateIndex:8790; appliedIndex:8789; }","duration":"144.518744ms","start":"2026-07-08T02:31:09.879938Z","end":"2026-07-08T02:31:10.024457Z","steps":["trace[1642767148] 'read index received' (duration: 112.060785ms)","trace[1642767148] 'applied index is now lower than readState.Index' (duration: 32.456769ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:10.024689Z","caller":"traceutil/trace.go:171","msg":"trace[1999667294] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"319.915185ms","start":"2026-07-08T02:31:09.70473Z","end":"2026-07-08T02:31:10.024645Z","steps":["trace[1999667294] 'process raft request' (duration: 287.25714ms)","trace[1999667294] 'compare' (duration: 32.330556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:10.024724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.772861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5wq4w\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-08T02:31:10.024899Z","caller":"traceutil/trace.go:171","msg":"trace[1491580147] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5wq4w; range_end:; response_count:1; response_revision:8484; }","duration":"145.000347ms","start":"2026-07-08T02:31:09.879886Z","end":"2026-07-08T02:31:10.024886Z","steps":["trace[1491580147] 'agreement among raft nodes before linearized reading' (duration: 144.686329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:10.024896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:09.704711Z","time spent":"320.084199ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:31:10.908802Z","caller":"traceutil/trace.go:171","msg":"trace[2038006685] linearizableReadLoop","detail":"{readStateIndex:8793; appliedIndex:8792; }","duration":"167.196323ms","start":"2026-07-08T02:31:10.741579Z","end":"2026-07-08T02:31:10.908776Z","steps":["trace[2038006685] 'read index received' (duration: 167.068659ms)","trace[2038006685] 'applied index is now lower than readState.Index' (duration: 126.264µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:10.908931Z","caller":"traceutil/trace.go:171","msg":"trace[1298667662] transaction","detail":"{read_only:false; response_revision:8487; number_of_response:1; }","duration":"183.673509ms","start":"2026-07-08T02:31:10.725219Z","end":"2026-07-08T02:31:10.908893Z","steps":["trace[1298667662] 'process raft request' (duration: 183.35105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:10.908992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.398569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:31:10.909034Z","caller":"traceutil/trace.go:171","msg":"trace[1154369113] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:8487; }","duration":"167.510672ms","start":"2026-07-08T02:31:10.741513Z","end":"2026-07-08T02:31:10.909023Z","steps":["trace[1154369113] 'agreement among raft nodes before linearized reading' (duration: 167.361597ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:10.944849Z","caller":"traceutil/trace.go:171","msg":"trace[1795353464] transaction","detail":"{read_only:false; response_revision:8488; number_of_response:1; }","duration":"179.129744ms","start":"2026-07-08T02:31:10.765693Z","end":"2026-07-08T02:31:10.944823Z","steps":["trace[1795353464] 'process raft request' (duration: 178.949819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:11.301543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.250228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:31:11.301625Z","caller":"traceutil/trace.go:171","msg":"trace[176090511] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:8488; }","duration":"292.393641ms","start":"2026-07-08T02:31:11.00921Z","end":"2026-07-08T02:31:11.301604Z","steps":["trace[176090511] 'count revisions from in-memory index tree' (duration: 292.127644ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:11.327626Z","caller":"traceutil/trace.go:171","msg":"trace[1600063861] linearizableReadLoop","detail":"{readStateIndex:8795; appliedIndex:8794; }","duration":"223.919605ms","start":"2026-07-08T02:31:11.103682Z","end":"2026-07-08T02:31:11.327602Z","steps":["trace[1600063861] 'read index received' (duration: 223.827222ms)","trace[1600063861] 'applied index is now lower than readState.Index' (duration: 91.393µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:11.327678Z","caller":"traceutil/trace.go:171","msg":"trace[976335385] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"233.620573ms","start":"2026-07-08T02:31:11.094029Z","end":"2026-07-08T02:31:11.32765Z","steps":["trace[976335385] 'process raft request' (duration: 233.418458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:11.328058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.063688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:11.32816Z","caller":"traceutil/trace.go:171","msg":"trace[1309156800] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8489; }","duration":"224.504371ms","start":"2026-07-08T02:31:11.103641Z","end":"2026-07-08T02:31:11.328145Z","steps":["trace[1309156800] 'agreement among raft nodes before linearized reading' (duration: 224.038758ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:11.401905Z","caller":"traceutil/trace.go:171","msg":"trace[1446643227] transaction","detail":"{read_only:false; response_revision:8490; number_of_response:1; }","duration":"274.726232ms","start":"2026-07-08T02:31:11.127138Z","end":"2026-07-08T02:31:11.401865Z","steps":["trace[1446643227] 'process raft request' (duration: 274.389663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:11.401917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.092478ms","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-07-08T02:31:11.402142Z","caller":"traceutil/trace.go:171","msg":"trace[522056240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8490; }","duration":"181.372856ms","start":"2026-07-08T02:31:11.220748Z","end":"2026-07-08T02:31:11.402121Z","steps":["trace[522056240] 'agreement among raft nodes before linearized reading' (duration: 180.965144ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:11.856078Z","caller":"traceutil/trace.go:171","msg":"trace[683141317] linearizableReadLoop","detail":"{readStateIndex:8799; appliedIndex:8798; }","duration":"141.983444ms","start":"2026-07-08T02:31:11.714075Z","end":"2026-07-08T02:31:11.856058Z","steps":["trace[683141317] 'read index received' (duration: 141.801149ms)","trace[683141317] 'applied index is now lower than readState.Index' (duration: 181.635µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:11.856157Z","caller":"traceutil/trace.go:171","msg":"trace[828702021] transaction","detail":"{read_only:false; response_revision:8493; number_of_response:1; }","duration":"147.376654ms","start":"2026-07-08T02:31:11.708741Z","end":"2026-07-08T02:31:11.856117Z","steps":["trace[828702021] 'process raft request' (duration: 147.193399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:11.856336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.244471ms","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-07-08T02:31:11.856417Z","caller":"traceutil/trace.go:171","msg":"trace[488816601] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8493; }","duration":"142.378565ms","start":"2026-07-08T02:31:11.714015Z","end":"2026-07-08T02:31:11.856393Z","steps":["trace[488816601] 'agreement among raft nodes before linearized reading' (duration: 142.129588ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:17.585407Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002286.wal"} {"level":"info","ts":"2026-07-08T02:31:35.684716Z","caller":"traceutil/trace.go:171","msg":"trace[1425855226] linearizableReadLoop","detail":"{readStateIndex:8986; appliedIndex:8985; }","duration":"120.291883ms","start":"2026-07-08T02:31:35.564399Z","end":"2026-07-08T02:31:35.684691Z","steps":["trace[1425855226] 'read index received' (duration: 117.571938ms)","trace[1425855226] 'applied index is now lower than readState.Index' (duration: 2.718795ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:35.684843Z","caller":"traceutil/trace.go:171","msg":"trace[1685902786] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"138.136867ms","start":"2026-07-08T02:31:35.546692Z","end":"2026-07-08T02:31:35.684829Z","steps":["trace[1685902786] 'process raft request' (duration: 135.330069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:35.68533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.91662ms","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-08T02:31:35.68539Z","caller":"traceutil/trace.go:171","msg":"trace[519659775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8673; }","duration":"121.020583ms","start":"2026-07-08T02:31:35.564354Z","end":"2026-07-08T02:31:35.685374Z","steps":["trace[519659775] 'agreement among raft nodes before linearized reading' (duration: 120.818537ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:39.187197Z","caller":"traceutil/trace.go:171","msg":"trace[346633110] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"386.50952ms","start":"2026-07-08T02:31:38.800669Z","end":"2026-07-08T02:31:39.187178Z","steps":["trace[346633110] 'process raft request' (duration: 386.409177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:39.187302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:38.80065Z","time spent":"386.599273ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:31:39.567146Z","caller":"traceutil/trace.go:171","msg":"trace[893291253] linearizableReadLoop","detail":"{readStateIndex:9006; appliedIndex:9005; }","duration":"183.132374ms","start":"2026-07-08T02:31:39.383989Z","end":"2026-07-08T02:31:39.567121Z","steps":["trace[893291253] 'read index received' (duration: 182.910998ms)","trace[893291253] 'applied index is now lower than readState.Index' (duration: 220.266µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:39.567255Z","caller":"traceutil/trace.go:171","msg":"trace[1572679907] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"551.439029ms","start":"2026-07-08T02:31:39.015785Z","end":"2026-07-08T02:31:39.567224Z","steps":["trace[1572679907] 'process raft request' (duration: 551.130751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:39.567406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:39.015742Z","time spent":"551.583564ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:31:39.567689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.67225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-08T02:31:39.567741Z","caller":"traceutil/trace.go:171","msg":"trace[970792865] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:8693; }","duration":"183.830604ms","start":"2026-07-08T02:31:39.383896Z","end":"2026-07-08T02:31:39.567727Z","steps":["trace[970792865] 'agreement among raft nodes before linearized reading' (duration: 183.345931ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:39.572396Z","caller":"traceutil/trace.go:171","msg":"trace[1915823002] transaction","detail":"{read_only:false; response_revision:8694; number_of_response:1; }","duration":"135.943497ms","start":"2026-07-08T02:31:39.43644Z","end":"2026-07-08T02:31:39.572383Z","steps":["trace[1915823002] 'process raft request' (duration: 135.774582ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:43.313618Z","caller":"traceutil/trace.go:171","msg":"trace[32831414] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"108.185918ms","start":"2026-07-08T02:31:43.205411Z","end":"2026-07-08T02:31:43.313597Z","steps":["trace[32831414] 'process raft request' (duration: 108.064405ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:43.611311Z","caller":"traceutil/trace.go:171","msg":"trace[2062702995] linearizableReadLoop","detail":"{readStateIndex:9027; appliedIndex:9026; }","duration":"114.557065ms","start":"2026-07-08T02:31:43.49673Z","end":"2026-07-08T02:31:43.611287Z","steps":["trace[2062702995] 'read index received' (duration: 114.420391ms)","trace[2062702995] 'applied index is now lower than readState.Index' (duration: 135.524µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:43.611359Z","caller":"traceutil/trace.go:171","msg":"trace[779111919] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"151.514619ms","start":"2026-07-08T02:31:43.459815Z","end":"2026-07-08T02:31:43.611329Z","steps":["trace[779111919] 'process raft request' (duration: 151.340564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:43.61143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.683098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:43.611464Z","caller":"traceutil/trace.go:171","msg":"trace[1332629316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8713; }","duration":"114.76761ms","start":"2026-07-08T02:31:43.496687Z","end":"2026-07-08T02:31:43.611455Z","steps":["trace[1332629316] 'agreement among raft nodes before linearized reading' (duration: 114.698728ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:51.596884Z","caller":"traceutil/trace.go:171","msg":"trace[125512883] linearizableReadLoop","detail":"{readStateIndex:9115; appliedIndex:9114; }","duration":"134.828576ms","start":"2026-07-08T02:31:51.462032Z","end":"2026-07-08T02:31:51.59686Z","steps":["trace[125512883] 'read index received' (duration: 125.694443ms)","trace[125512883] 'applied index is now lower than readState.Index' (duration: 9.133093ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:51.596962Z","caller":"traceutil/trace.go:171","msg":"trace[1797945305] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8796; }","duration":"171.731018ms","start":"2026-07-08T02:31:51.425212Z","end":"2026-07-08T02:31:51.596943Z","steps":["trace[1797945305] 'process raft request' (duration: 162.634186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:51.597114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.072953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x7dlh\" ","response":"range_response_count:1 size:4664"} {"level":"info","ts":"2026-07-08T02:31:51.597159Z","caller":"traceutil/trace.go:171","msg":"trace[1945129857] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x7dlh; range_end:; response_count:1; response_revision:8796; }","duration":"135.161435ms","start":"2026-07-08T02:31:51.461987Z","end":"2026-07-08T02:31:51.597148Z","steps":["trace[1945129857] 'agreement among raft nodes before linearized reading' (duration: 135.001291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:51.597192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.87557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-x7dlh\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-07-08T02:31:51.597226Z","caller":"traceutil/trace.go:171","msg":"trace[1319394498] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-x7dlh; range_end:; response_count:1; response_revision:8796; }","duration":"133.945271ms","start":"2026-07-08T02:31:51.463274Z","end":"2026-07-08T02:31:51.597219Z","steps":["trace[1319394498] 'agreement among raft nodes before linearized reading' (duration: 133.816368ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:51.789357Z","caller":"traceutil/trace.go:171","msg":"trace[1013204591] transaction","detail":"{read_only:false; response_revision:8797; number_of_response:1; }","duration":"183.772542ms","start":"2026-07-08T02:31:51.605535Z","end":"2026-07-08T02:31:51.789307Z","steps":["trace[1013204591] 'process raft request' (duration: 183.621527ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:52.023474Z","caller":"traceutil/trace.go:171","msg":"trace[178495437] transaction","detail":"{read_only:false; response_revision:8801; number_of_response:1; }","duration":"350.390728ms","start":"2026-07-08T02:31:51.673074Z","end":"2026-07-08T02:31:52.023464Z","steps":["trace[178495437] 'process raft request' (duration: 350.362678ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:52.023497Z","caller":"traceutil/trace.go:171","msg":"trace[261968919] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8798; }","duration":"415.203924ms","start":"2026-07-08T02:31:51.608258Z","end":"2026-07-08T02:31:52.023462Z","steps":["trace[261968919] 'process raft request' (duration: 414.934017ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:52.023571Z","caller":"traceutil/trace.go:171","msg":"trace[1854353403] transaction","detail":"{read_only:false; response_revision:8800; number_of_response:1; }","duration":"351.035936ms","start":"2026-07-08T02:31:51.672485Z","end":"2026-07-08T02:31:52.023521Z","steps":["trace[1854353403] 'process raft request' (duration: 350.925833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.023621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.673053Z","time spent":"350.470031ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:31:52.023697Z","caller":"traceutil/trace.go:171","msg":"trace[325181615] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"352.370573ms","start":"2026-07-08T02:31:51.671124Z","end":"2026-07-08T02:31:52.023494Z","steps":["trace[325181615] 'process raft request' (duration: 352.23806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.023745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.608251Z","time spent":"415.377789ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59,"response count":0,"response size":4704,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:31:52.02329Z","caller":"traceutil/trace.go:171","msg":"trace[1058349613] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8798; }","duration":"416.539252ms","start":"2026-07-08T02:31:51.606713Z","end":"2026-07-08T02:31:52.023253Z","steps":["trace[1058349613] 'process raft request' (duration: 416.327446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.023948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.671097Z","time spent":"352.707393ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:31:52.023727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.672471Z","time spent":"351.174361ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:31:52.024089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.606706Z","time spent":"417.22245ms","remote":"127.0.0.1:48572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":42,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-07-08T02:31:52.160973Z","caller":"traceutil/trace.go:171","msg":"trace[1388626648] linearizableReadLoop","detail":"{readStateIndex:9122; appliedIndex:9116; }","duration":"352.677172ms","start":"2026-07-08T02:31:51.808274Z","end":"2026-07-08T02:31:52.160951Z","steps":["trace[1388626648] 'read index received' (duration: 214.7404ms)","trace[1388626648] 'applied index is now lower than readState.Index' (duration: 137.935752ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:52.161082Z","caller":"traceutil/trace.go:171","msg":"trace[1756188781] transaction","detail":"{read_only:false; response_revision:8802; number_of_response:1; }","duration":"358.849793ms","start":"2026-07-08T02:31:51.802201Z","end":"2026-07-08T02:31:52.161051Z","steps":["trace[1756188781] 'process raft request' (duration: 310.463722ms)","trace[1756188781] 'compare' (duration: 48.104183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:52.161155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.871947ms","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-08T02:31:52.16118Z","caller":"traceutil/trace.go:171","msg":"trace[2119879381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8802; }","duration":"352.940259ms","start":"2026-07-08T02:31:51.808232Z","end":"2026-07-08T02:31:52.161172Z","steps":["trace[2119879381] 'agreement among raft nodes before linearized reading' (duration: 352.823706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.161178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.8666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-08T02:31:52.161211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.808222Z","time spent":"352.98136ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T02:31:52.161231Z","caller":"traceutil/trace.go:171","msg":"trace[1221744082] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:8802; }","duration":"316.945562ms","start":"2026-07-08T02:31:51.844271Z","end":"2026-07-08T02:31:52.161217Z","steps":["trace[1221744082] 'agreement among raft nodes before linearized reading' (duration: 316.862659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.161242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.802181Z","time spent":"358.981517ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:31:52.161278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:51.84426Z","time spent":"317.008074ms","remote":"127.0.0.1:51360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-07-08T02:31:52.161408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.19976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:52.161443Z","caller":"traceutil/trace.go:171","msg":"trace[1325552486] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8802; }","duration":"173.269742ms","start":"2026-07-08T02:31:51.988165Z","end":"2026-07-08T02:31:52.161435Z","steps":["trace[1325552486] 'agreement among raft nodes before linearized reading' (duration: 173.22023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.16153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.915042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x7dlh\" ","response":"range_response_count:1 size:4669"} {"level":"info","ts":"2026-07-08T02:31:52.161642Z","caller":"traceutil/trace.go:171","msg":"trace[871814646] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x7dlh; range_end:; response_count:1; response_revision:8802; }","duration":"120.143659ms","start":"2026-07-08T02:31:52.041479Z","end":"2026-07-08T02:31:52.161623Z","steps":["trace[871814646] 'agreement among raft nodes before linearized reading' (duration: 119.714207ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:52.387506Z","caller":"traceutil/trace.go:171","msg":"trace[275221372] linearizableReadLoop","detail":"{readStateIndex:9125; appliedIndex:9123; }","duration":"133.211ms","start":"2026-07-08T02:31:52.254272Z","end":"2026-07-08T02:31:52.387483Z","steps":["trace[275221372] 'read index received' (duration: 132.875411ms)","trace[275221372] 'applied index is now lower than readState.Index' (duration: 334.669µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:52.387594Z","caller":"traceutil/trace.go:171","msg":"trace[1100014270] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"221.295361ms","start":"2026-07-08T02:31:52.166285Z","end":"2026-07-08T02:31:52.38758Z","steps":["trace[1100014270] 'process raft request' (duration: 220.920321ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:52.387593Z","caller":"traceutil/trace.go:171","msg":"trace[452409742] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"217.788194ms","start":"2026-07-08T02:31:52.169778Z","end":"2026-07-08T02:31:52.387566Z","steps":["trace[452409742] 'process raft request' (duration: 217.637259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.387742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.470318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-08T02:31:52.387784Z","caller":"traceutil/trace.go:171","msg":"trace[815407625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8805; }","duration":"133.598931ms","start":"2026-07-08T02:31:52.254175Z","end":"2026-07-08T02:31:52.387773Z","steps":["trace[815407625] 'agreement among raft nodes before linearized reading' (duration: 133.442647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.387902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.281691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5wq4w\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-08T02:31:52.387945Z","caller":"traceutil/trace.go:171","msg":"trace[1670009490] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5wq4w; range_end:; response_count:1; response_revision:8805; }","duration":"103.380894ms","start":"2026-07-08T02:31:52.284553Z","end":"2026-07-08T02:31:52.387934Z","steps":["trace[1670009490] 'agreement among raft nodes before linearized reading' (duration: 103.22876ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:52.540863Z","caller":"traceutil/trace.go:171","msg":"trace[97063721] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"144.607317ms","start":"2026-07-08T02:31:52.39623Z","end":"2026-07-08T02:31:52.540837Z","steps":["trace[97063721] 'process raft request' (duration: 135.551817ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:52.540872Z","caller":"traceutil/trace.go:171","msg":"trace[1130547141] linearizableReadLoop","detail":"{readStateIndex:9127; appliedIndex:9126; }","duration":"124.695775ms","start":"2026-07-08T02:31:52.416136Z","end":"2026-07-08T02:31:52.540832Z","steps":["trace[1130547141] 'read index received' (duration: 115.551032ms)","trace[1130547141] 'applied index is now lower than readState.Index' (duration: 9.143523ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:52.541315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.495745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228735"} {"level":"info","ts":"2026-07-08T02:31:52.541374Z","caller":"traceutil/trace.go:171","msg":"trace[1268865218] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8807; }","duration":"148.606658ms","start":"2026-07-08T02:31:52.392756Z","end":"2026-07-08T02:31:52.541363Z","steps":["trace[1268865218] 'agreement among raft nodes before linearized reading' (duration: 148.159605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.54145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.958829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-x7dlh\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-07-08T02:31:52.541562Z","caller":"traceutil/trace.go:171","msg":"trace[578473970] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-x7dlh; range_end:; response_count:1; response_revision:8807; }","duration":"122.098743ms","start":"2026-07-08T02:31:52.419425Z","end":"2026-07-08T02:31:52.541523Z","steps":["trace[578473970] 'agreement among raft nodes before linearized reading' (duration: 121.845206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.799803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.315119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:31:52.799926Z","caller":"traceutil/trace.go:171","msg":"trace[1625131424] linearizableReadLoop","detail":"{readStateIndex:9128; appliedIndex:9127; }","duration":"158.459881ms","start":"2026-07-08T02:31:52.64145Z","end":"2026-07-08T02:31:52.79991Z","steps":["trace[1625131424] 'read index received' (duration: 13.897825ms)","trace[1625131424] 'applied index is now lower than readState.Index' (duration: 144.560796ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:52.800011Z","caller":"traceutil/trace.go:171","msg":"trace[1880618291] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"225.468447ms","start":"2026-07-08T02:31:52.574532Z","end":"2026-07-08T02:31:52.8Z","steps":["trace[1880618291] 'process raft request' (duration: 80.890231ms)","trace[1880618291] 'compare' (duration: 144.090513ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:52.800331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.79701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T02:31:52.800435Z","caller":"traceutil/trace.go:171","msg":"trace[168992153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8808; }","duration":"106.961034ms","start":"2026-07-08T02:31:52.693453Z","end":"2026-07-08T02:31:52.800414Z","steps":["trace[168992153] 'agreement among raft nodes before linearized reading' (duration: 106.654485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.800426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.945294ms","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-07-08T02:31:52.800613Z","caller":"traceutil/trace.go:171","msg":"trace[1534126887] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8808; }","duration":"159.178581ms","start":"2026-07-08T02:31:52.641411Z","end":"2026-07-08T02:31:52.80059Z","steps":["trace[1534126887] 'agreement among raft nodes before linearized reading' (duration: 158.851532ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:32:05.558586Z","caller":"traceutil/trace.go:171","msg":"trace[1594973322] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"108.778644ms","start":"2026-07-08T02:32:05.449768Z","end":"2026-07-08T02:32:05.558546Z","steps":["trace[1594973322] 'process raft request' (duration: 108.428754ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:33:22.211579Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T02:33:22.220121Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T02:33:22.220198Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T02:35:26.266201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8076} {"level":"info","ts":"2026-07-08T02:35:26.338296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8076,"took":"70.081835ms","hash":4257769744,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":28483584,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T02:35:26.338373Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4257769744,"revision":8076,"compact-revision":5996} {"level":"info","ts":"2026-07-08T02:38:47.806629Z","caller":"traceutil/trace.go:171","msg":"trace[593919248] transaction","detail":"{read_only:false; response_revision:11986; number_of_response:1; }","duration":"148.983611ms","start":"2026-07-08T02:38:47.657604Z","end":"2026-07-08T02:38:47.806588Z","steps":["trace[593919248] 'process raft request' (duration: 83.913353ms)","trace[593919248] 'compare' (duration: 64.861152ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:38:47.880886Z","caller":"traceutil/trace.go:171","msg":"trace[1342163053] transaction","detail":"{read_only:false; response_revision:11987; number_of_response:1; }","duration":"185.99966ms","start":"2026-07-08T02:38:47.694863Z","end":"2026-07-08T02:38:47.880863Z","steps":["trace[1342163053] 'process raft request' (duration: 185.847456ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:38:49.150541Z","caller":"traceutil/trace.go:171","msg":"trace[1496724105] transaction","detail":"{read_only:false; response_revision:11995; number_of_response:1; }","duration":"194.311442ms","start":"2026-07-08T02:38:48.956201Z","end":"2026-07-08T02:38:49.150512Z","steps":["trace[1496724105] 'process raft request' (duration: 194.153687ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:38:49.561916Z","caller":"traceutil/trace.go:171","msg":"trace[916465076] transaction","detail":"{read_only:false; response_revision:11996; number_of_response:1; }","duration":"133.578223ms","start":"2026-07-08T02:38:49.42829Z","end":"2026-07-08T02:38:49.561868Z","steps":["trace[916465076] 'process raft request' (duration: 54.093134ms)","trace[916465076] 'compare' (duration: 79.084828ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:40:26.276233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10481} {"level":"info","ts":"2026-07-08T02:40:26.356043Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10481,"took":"77.135445ms","hash":164022031,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":26370048,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-08T02:40:26.356097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":164022031,"revision":10481,"compact-revision":8076} {"level":"info","ts":"2026-07-08T02:45:26.283247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12645} {"level":"info","ts":"2026-07-08T02:45:26.353532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12645,"took":"68.091583ms","hash":3009808632,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":27193344,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-08T02:45:26.353591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3009808632,"revision":12645,"compact-revision":10481} {"level":"warn","ts":"2026-07-08T02:45:26.720856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.792323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:45:26.720986Z","caller":"traceutil/trace.go:171","msg":"trace[1582607521] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:14651; }","duration":"104.961187ms","start":"2026-07-08T02:45:26.615977Z","end":"2026-07-08T02:45:26.720939Z","steps":["trace[1582607521] 'count revisions from in-memory index tree' (duration: 104.642519ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:27.588725Z","caller":"traceutil/trace.go:171","msg":"trace[2082130419] linearizableReadLoop","detail":"{readStateIndex:15172; appliedIndex:15171; }","duration":"191.835642ms","start":"2026-07-08T02:45:27.396866Z","end":"2026-07-08T02:45:27.588701Z","steps":["trace[2082130419] 'read index received' (duration: 191.673428ms)","trace[2082130419] 'applied index is now lower than readState.Index' (duration: 161.124µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:45:27.589206Z","caller":"traceutil/trace.go:171","msg":"trace[57945737] transaction","detail":"{read_only:false; response_revision:14657; number_of_response:1; }","duration":"204.140015ms","start":"2026-07-08T02:45:27.385044Z","end":"2026-07-08T02:45:27.589184Z","steps":["trace[57945737] 'process raft request' (duration: 203.499377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:45:27.58903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.148252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-jfkxs\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-07-08T02:45:27.589495Z","caller":"traceutil/trace.go:171","msg":"trace[1053524914] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-jfkxs; range_end:; response_count:1; response_revision:14657; }","duration":"192.634085ms","start":"2026-07-08T02:45:27.396851Z","end":"2026-07-08T02:45:27.589485Z","steps":["trace[1053524914] 'agreement among raft nodes before linearized reading' (duration: 192.018938ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:27.782252Z","caller":"traceutil/trace.go:171","msg":"trace[624984932] linearizableReadLoop","detail":"{readStateIndex:15173; appliedIndex:15172; }","duration":"165.13026ms","start":"2026-07-08T02:45:27.617105Z","end":"2026-07-08T02:45:27.782236Z","steps":["trace[624984932] 'read index received' (duration: 100.806242ms)","trace[624984932] 'applied index is now lower than readState.Index' (duration: 64.323358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:45:27.782389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.285695ms","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-08T02:45:27.782408Z","caller":"traceutil/trace.go:171","msg":"trace[1712865621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14658; }","duration":"165.352337ms","start":"2026-07-08T02:45:27.61705Z","end":"2026-07-08T02:45:27.782402Z","steps":["trace[1712865621] 'agreement among raft nodes before linearized reading' (duration: 165.244994ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:27.782574Z","caller":"traceutil/trace.go:171","msg":"trace[1315621005] transaction","detail":"{read_only:false; response_revision:14658; number_of_response:1; }","duration":"182.679119ms","start":"2026-07-08T02:45:27.599862Z","end":"2026-07-08T02:45:27.782541Z","steps":["trace[1315621005] 'process raft request' (duration: 118.027821ms)","trace[1315621005] 'compare' (duration: 64.272737ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:45:37.207676Z","caller":"traceutil/trace.go:171","msg":"trace[989955319] transaction","detail":"{read_only:false; response_revision:14825; number_of_response:1; }","duration":"291.276197ms","start":"2026-07-08T02:45:36.916349Z","end":"2026-07-08T02:45:37.207625Z","steps":["trace[989955319] 'process raft request' (duration: 290.873156ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:37.232289Z","caller":"traceutil/trace.go:171","msg":"trace[282293806] transaction","detail":"{read_only:false; response_revision:14826; number_of_response:1; }","duration":"266.787386ms","start":"2026-07-08T02:45:36.965458Z","end":"2026-07-08T02:45:37.232245Z","steps":["trace[282293806] 'process raft request' (duration: 266.58703ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:37.232397Z","caller":"traceutil/trace.go:171","msg":"trace[1256623477] transaction","detail":"{read_only:false; response_revision:14828; number_of_response:1; }","duration":"139.93045ms","start":"2026-07-08T02:45:37.092418Z","end":"2026-07-08T02:45:37.232349Z","steps":["trace[1256623477] 'process raft request' (duration: 139.785236ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:37.232466Z","caller":"traceutil/trace.go:171","msg":"trace[1058891607] transaction","detail":"{read_only:false; response_revision:14827; number_of_response:1; }","duration":"164.261086ms","start":"2026-07-08T02:45:37.068179Z","end":"2026-07-08T02:45:37.23244Z","steps":["trace[1058891607] 'process raft request' (duration: 163.969128ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:37.23266Z","caller":"traceutil/trace.go:171","msg":"trace[774855203] transaction","detail":"{read_only:false; response_revision:14829; number_of_response:1; }","duration":"138.293244ms","start":"2026-07-08T02:45:37.094084Z","end":"2026-07-08T02:45:37.232378Z","steps":["trace[774855203] 'process raft request' (duration: 138.14852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:06.446853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.891755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:47:06.44699Z","caller":"traceutil/trace.go:171","msg":"trace[2053699914] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:15857; }","duration":"144.08909ms","start":"2026-07-08T02:47:06.302862Z","end":"2026-07-08T02:47:06.446952Z","steps":["trace[2053699914] 'count revisions from in-memory index tree' (duration: 143.576926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:07.637025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.971398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:47:07.637116Z","caller":"traceutil/trace.go:171","msg":"trace[94230537] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15861; }","duration":"185.107792ms","start":"2026-07-08T02:47:07.451985Z","end":"2026-07-08T02:47:07.637093Z","steps":["trace[94230537] 'range keys from in-memory index tree' (duration: 184.869846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:07.637304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.127882ms","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-08T02:47:07.637341Z","caller":"traceutil/trace.go:171","msg":"trace[866700995] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15861; }","duration":"185.202614ms","start":"2026-07-08T02:47:07.452127Z","end":"2026-07-08T02:47:07.63733Z","steps":["trace[866700995] 'range keys from in-memory index tree' (duration: 185.0456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:07.637337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.04283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:47:07.6374Z","caller":"traceutil/trace.go:171","msg":"trace[855003956] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15861; }","duration":"185.128263ms","start":"2026-07-08T02:47:07.452258Z","end":"2026-07-08T02:47:07.637386Z","steps":["trace[855003956] 'range keys from in-memory index tree' (duration: 184.959848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:07.636715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.670757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-07-08T02:47:07.637412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.810276ms","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-08T02:47:07.637444Z","caller":"traceutil/trace.go:171","msg":"trace[2004444867] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"183.879768ms","start":"2026-07-08T02:47:07.453555Z","end":"2026-07-08T02:47:07.637435Z","steps":["trace[2004444867] 'range keys from in-memory index tree' (duration: 183.648081ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:07.637442Z","caller":"traceutil/trace.go:171","msg":"trace[556237165] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15861; }","duration":"159.441518ms","start":"2026-07-08T02:47:07.477986Z","end":"2026-07-08T02:47:07.637428Z","steps":["trace[556237165] 'range keys from in-memory index tree' (duration: 158.509512ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:07.781996Z","caller":"traceutil/trace.go:171","msg":"trace[1531815131] transaction","detail":"{read_only:false; response_revision:15862; number_of_response:1; }","duration":"164.638152ms","start":"2026-07-08T02:47:07.617336Z","end":"2026-07-08T02:47:07.781974Z","steps":["trace[1531815131] 'process raft request' (duration: 164.519539ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:07.782887Z","caller":"traceutil/trace.go:171","msg":"trace[634096072] linearizableReadLoop","detail":"{readStateIndex:16416; appliedIndex:16413; }","duration":"141.874798ms","start":"2026-07-08T02:47:07.640968Z","end":"2026-07-08T02:47:07.782843Z","steps":["trace[634096072] 'read index received' (duration: 140.909901ms)","trace[634096072] 'applied index is now lower than readState.Index' (duration: 963.727µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:47:07.782929Z","caller":"traceutil/trace.go:171","msg":"trace[1634440113] transaction","detail":"{read_only:false; response_revision:15863; number_of_response:1; }","duration":"164.998543ms","start":"2026-07-08T02:47:07.617891Z","end":"2026-07-08T02:47:07.78289Z","steps":["trace[1634440113] 'process raft request' (duration: 164.744626ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:07.783034Z","caller":"traceutil/trace.go:171","msg":"trace[560501058] transaction","detail":"{read_only:false; response_revision:15864; number_of_response:1; }","duration":"155.619981ms","start":"2026-07-08T02:47:07.627326Z","end":"2026-07-08T02:47:07.782946Z","steps":["trace[560501058] 'process raft request' (duration: 155.465587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:07.783145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.344493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T02:47:07.783311Z","caller":"traceutil/trace.go:171","msg":"trace[1381657761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15864; }","duration":"114.551908ms","start":"2026-07-08T02:47:07.668744Z","end":"2026-07-08T02:47:07.783296Z","steps":["trace[1381657761] 'agreement among raft nodes before linearized reading' (duration: 114.27835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:07.784243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.261368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769684"} {"level":"info","ts":"2026-07-08T02:47:07.784301Z","caller":"traceutil/trace.go:171","msg":"trace[786862874] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15864; }","duration":"143.34876ms","start":"2026-07-08T02:47:07.640943Z","end":"2026-07-08T02:47:07.784292Z","steps":["trace[786862874] 'agreement among raft nodes before linearized reading' (duration: 142.059984ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:50:26.290074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14647} {"level":"info","ts":"2026-07-08T02:50:26.36815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14647,"took":"75.602265ms","hash":2516789234,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":34344960,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-08T02:50:26.368206Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2516789234,"revision":14647,"compact-revision":12645} {"level":"warn","ts":"2026-07-08T02:53:03.984569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.910291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T02:53:03.984648Z","caller":"traceutil/trace.go:171","msg":"trace[463412914] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:18459; }","duration":"230.034504ms","start":"2026-07-08T02:53:03.754596Z","end":"2026-07-08T02:53:03.98463Z","steps":["trace[463412914] 'count revisions from in-memory index tree' (duration: 229.788928ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:55:26.297534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17416} {"level":"info","ts":"2026-07-08T02:55:26.393922Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17416,"took":"93.996132ms","hash":2987220388,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":30752768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T02:55:26.393979Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2987220388,"revision":17416,"compact-revision":14647} {"level":"info","ts":"2026-07-08T02:55:29.613952Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T02:55:29.618923Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-08T02:55:29.619062Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-08T03:00:26.307481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19309} {"level":"info","ts":"2026-07-08T03:00:26.401215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19309,"took":"91.366509ms","hash":533923255,"current-db-size-bytes":58826752,"current-db-size":"59 MB","current-db-size-in-use-bytes":25309184,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-08T03:00:26.40127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":533923255,"revision":19309,"compact-revision":17416} {"level":"warn","ts":"2026-07-08T03:00:40.395602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317065859829518,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T03:00:40.867344Z","caller":"traceutil/trace.go:171","msg":"trace[1527751076] linearizableReadLoop","detail":"{readStateIndex:21713; appliedIndex:21712; }","duration":"972.016614ms","start":"2026-07-08T03:00:39.895298Z","end":"2026-07-08T03:00:40.867315Z","steps":["trace[1527751076] 'read index received' (duration: 971.88178ms)","trace[1527751076] 'applied index is now lower than readState.Index' (duration: 133.574µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:00:40.867481Z","caller":"traceutil/trace.go:171","msg":"trace[789160056] transaction","detail":"{read_only:false; response_revision:20970; number_of_response:1; }","duration":"975.204702ms","start":"2026-07-08T03:00:39.892259Z","end":"2026-07-08T03:00:40.867464Z","steps":["trace[789160056] 'process raft request' (duration: 974.868673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.867615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:39.89224Z","time spent":"975.287125ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:00:40.867684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.369344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/barbican-rabbitmq-user\" ","response":"range_response_count:1 size:710"} {"level":"info","ts":"2026-07-08T03:00:40.867753Z","caller":"traceutil/trace.go:171","msg":"trace[957719372] range","detail":"{range_begin:/registry/secrets/openstack/barbican-rabbitmq-user; range_end:; response_count:1; response_revision:20970; }","duration":"972.458216ms","start":"2026-07-08T03:00:39.895278Z","end":"2026-07-08T03:00:40.867737Z","steps":["trace[957719372] 'agreement among raft nodes before linearized reading' (duration: 972.2369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.86781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:39.895271Z","time spent":"972.527758ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":734,"request content":"key:\"/registry/secrets/openstack/barbican-rabbitmq-user\" "} {"level":"info","ts":"2026-07-08T03:00:40.868355Z","caller":"traceutil/trace.go:171","msg":"trace[2013796145] transaction","detail":"{read_only:false; response_revision:20971; number_of_response:1; }","duration":"209.212816ms","start":"2026-07-08T03:00:40.659124Z","end":"2026-07-08T03:00:40.868337Z","steps":["trace[2013796145] 'process raft request' (duration: 208.923768ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:00:40.86838Z","caller":"traceutil/trace.go:171","msg":"trace[1259321959] transaction","detail":"{read_only:false; response_revision:20972; number_of_response:1; }","duration":"198.917339ms","start":"2026-07-08T03:00:40.669433Z","end":"2026-07-08T03:00:40.86835Z","steps":["trace[1259321959] 'process raft request' (duration: 198.790655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.869022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.018305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:00:40.869105Z","caller":"traceutil/trace.go:171","msg":"trace[1681463927] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:20974; }","duration":"349.121048ms","start":"2026-07-08T03:00:40.51996Z","end":"2026-07-08T03:00:40.869081Z","steps":["trace[1681463927] 'agreement among raft nodes before linearized reading' (duration: 348.976234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.869137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.237202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-07-08T03:00:40.869171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:40.51995Z","time spent":"349.20139ms","remote":"127.0.0.1:50884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-07-08T03:00:40.869202Z","caller":"traceutil/trace.go:171","msg":"trace[819367744] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20974; }","duration":"748.341224ms","start":"2026-07-08T03:00:40.120841Z","end":"2026-07-08T03:00:40.869182Z","steps":["trace[819367744] 'agreement among raft nodes before linearized reading' (duration: 748.19687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.869245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.130049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-07-08T03:00:40.869255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:40.120819Z","time spent":"748.424597ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-08T03:00:40.8691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.585248ms","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-07-08T03:00:40.869312Z","caller":"traceutil/trace.go:171","msg":"trace[1549441792] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20974; }","duration":"411.22253ms","start":"2026-07-08T03:00:40.458068Z","end":"2026-07-08T03:00:40.869291Z","steps":["trace[1549441792] 'agreement among raft nodes before linearized reading' (duration: 411.071196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.869417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.629984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-07-08T03:00:40.869493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:40.458048Z","time spent":"411.427926ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-08T03:00:40.869283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.050249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T03:00:40.869522Z","caller":"traceutil/trace.go:171","msg":"trace[594012001] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20974; }","duration":"842.81407ms","start":"2026-07-08T03:00:40.026683Z","end":"2026-07-08T03:00:40.869497Z","steps":["trace[594012001] 'agreement among raft nodes before linearized reading' (duration: 842.477321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.869576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:40.026659Z","time spent":"842.906352ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-07-08T03:00:40.869598Z","caller":"traceutil/trace.go:171","msg":"trace[1552200434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20974; }","duration":"186.40085ms","start":"2026-07-08T03:00:40.683167Z","end":"2026-07-08T03:00:40.869568Z","steps":["trace[1552200434] 'agreement among raft nodes before linearized reading' (duration: 186.007169ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:00:40.869403Z","caller":"traceutil/trace.go:171","msg":"trace[382703433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20974; }","duration":"716.842766ms","start":"2026-07-08T03:00:40.152496Z","end":"2026-07-08T03:00:40.869338Z","steps":["trace[382703433] 'agreement among raft nodes before linearized reading' (duration: 716.492286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.869714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:40.15243Z","time spent":"717.251137ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T03:00:40.869771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.190668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/glance-api\" ","response":"range_response_count:1 size:523"} {"level":"info","ts":"2026-07-08T03:00:40.86983Z","caller":"traceutil/trace.go:171","msg":"trace[68197773] range","detail":"{range_begin:/registry/serviceaccounts/openstack/glance-api; range_end:; response_count:1; response_revision:20974; }","duration":"953.319412ms","start":"2026-07-08T03:00:39.916495Z","end":"2026-07-08T03:00:40.869814Z","steps":["trace[68197773] 'agreement among raft nodes before linearized reading' (duration: 952.420317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:40.869884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:39.91645Z","time spent":"953.420964ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":547,"request content":"key:\"/registry/serviceaccounts/openstack/glance-api\" "} {"level":"warn","ts":"2026-07-08T03:00:47.601734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576317065859829804,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T03:00:47.804673Z","caller":"traceutil/trace.go:171","msg":"trace[1058522656] transaction","detail":"{read_only:false; response_revision:21000; number_of_response:1; }","duration":"707.377332ms","start":"2026-07-08T03:00:47.09727Z","end":"2026-07-08T03:00:47.804648Z","steps":["trace[1058522656] 'process raft request' (duration: 701.529719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.80481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.097251Z","time spent":"707.490655ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:00:47.80505Z","caller":"traceutil/trace.go:171","msg":"trace[320167061] transaction","detail":"{read_only:false; response_revision:21001; number_of_response:1; }","duration":"704.400518ms","start":"2026-07-08T03:00:47.10062Z","end":"2026-07-08T03:00:47.805021Z","steps":["trace[320167061] 'process raft request' (duration: 703.944056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.805214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.100605Z","time spent":"704.535083ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:00:47.805831Z","caller":"traceutil/trace.go:171","msg":"trace[1121130237] transaction","detail":"{read_only:false; response_revision:21002; number_of_response:1; }","duration":"664.427653ms","start":"2026-07-08T03:00:47.141381Z","end":"2026-07-08T03:00:47.805808Z","steps":["trace[1121130237] 'process raft request' (duration: 663.655292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.805925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.14137Z","time spent":"664.508066ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:00:47.806008Z","caller":"traceutil/trace.go:171","msg":"trace[1469329892] transaction","detail":"{read_only:false; response_revision:21003; number_of_response:1; }","duration":"644.353974ms","start":"2026-07-08T03:00:47.161644Z","end":"2026-07-08T03:00:47.805998Z","steps":["trace[1469329892] 'process raft request' (duration: 644.125147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.806049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.161397Z","time spent":"644.630631ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:00:47.806229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.081417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-08T03:00:47.806299Z","caller":"traceutil/trace.go:171","msg":"trace[1036430118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21003; }","duration":"705.176011ms","start":"2026-07-08T03:00:47.101107Z","end":"2026-07-08T03:00:47.806283Z","steps":["trace[1036430118] 'agreement among raft nodes before linearized reading' (duration: 705.002266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.806329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.101101Z","time spent":"705.221932ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T03:00:47.805908Z","caller":"traceutil/trace.go:171","msg":"trace[902175822] linearizableReadLoop","detail":"{readStateIndex:21747; appliedIndex:21743; }","duration":"704.747418ms","start":"2026-07-08T03:00:47.10113Z","end":"2026-07-08T03:00:47.805877Z","steps":["trace[902175822] 'read index received' (duration: 697.680531ms)","trace[902175822] 'applied index is now lower than readState.Index' (duration: 7.065677ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:00:47.806868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.915013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T03:00:47.806931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.124729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-07-08T03:00:47.80695Z","caller":"traceutil/trace.go:171","msg":"trace[1724857964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21003; }","duration":"633.994835ms","start":"2026-07-08T03:00:47.172943Z","end":"2026-07-08T03:00:47.806938Z","steps":["trace[1724857964] 'agreement among raft nodes before linearized reading' (duration: 633.850361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.806991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.172892Z","time spent":"634.092567ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T03:00:47.807Z","caller":"traceutil/trace.go:171","msg":"trace[821797321] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:21003; }","duration":"705.241652ms","start":"2026-07-08T03:00:47.101741Z","end":"2026-07-08T03:00:47.806983Z","steps":["trace[821797321] 'agreement among raft nodes before linearized reading' (duration: 705.048297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.80705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.101727Z","time spent":"705.314354ms","remote":"127.0.0.1:42790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-08T03:00:47.807124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.647617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-bv2s4\" ","response":"range_response_count:1 size:9788"} {"level":"warn","ts":"2026-07-08T03:00:47.807169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.797588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-api-certs\" ","response":"range_response_count:1 size:4319"} {"level":"info","ts":"2026-07-08T03:00:47.807166Z","caller":"traceutil/trace.go:171","msg":"trace[336790372] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-bv2s4; range_end:; response_count:1; response_revision:21003; }","duration":"371.731039ms","start":"2026-07-08T03:00:47.435422Z","end":"2026-07-08T03:00:47.807153Z","steps":["trace[336790372] 'agreement among raft nodes before linearized reading' (duration: 371.612786ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:00:47.807199Z","caller":"traceutil/trace.go:171","msg":"trace[640581702] range","detail":"{range_begin:/registry/secrets/openstack/keystone-api-certs; range_end:; response_count:1; response_revision:21003; }","duration":"586.86226ms","start":"2026-07-08T03:00:47.22033Z","end":"2026-07-08T03:00:47.807192Z","steps":["trace[640581702] 'agreement among raft nodes before linearized reading' (duration: 586.758187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.807205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.435407Z","time spent":"371.792631ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-bv2s4\" "} {"level":"warn","ts":"2026-07-08T03:00:47.807218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.220312Z","time spent":"586.901892ms","remote":"127.0.0.1:42704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":4343,"request content":"key:\"/registry/secrets/openstack/keystone-api-certs\" "} {"level":"warn","ts":"2026-07-08T03:00:47.807373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.487564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-08T03:00:47.807442Z","caller":"traceutil/trace.go:171","msg":"trace[1465391453] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21003; }","duration":"646.591157ms","start":"2026-07-08T03:00:47.160839Z","end":"2026-07-08T03:00:47.807431Z","steps":["trace[1465391453] 'agreement among raft nodes before linearized reading' (duration: 646.445883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.807467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.108615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/keystone-rabbit-init\" ","response":"range_response_count:1 size:545"} {"level":"warn","ts":"2026-07-08T03:00:47.807475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.160823Z","time spent":"646.643578ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-08T03:00:47.807491Z","caller":"traceutil/trace.go:171","msg":"trace[1609165712] range","detail":"{range_begin:/registry/serviceaccounts/openstack/keystone-rabbit-init; range_end:; response_count:1; response_revision:21003; }","duration":"659.157387ms","start":"2026-07-08T03:00:47.148326Z","end":"2026-07-08T03:00:47.807483Z","steps":["trace[1609165712] 'agreement among raft nodes before linearized reading' (duration: 659.091425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.807511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.148311Z","time spent":"659.196018ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":569,"request content":"key:\"/registry/serviceaccounts/openstack/keystone-rabbit-init\" "} {"level":"warn","ts":"2026-07-08T03:00:47.807681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.268586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-08T03:00:47.807699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.8115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-07-08T03:00:47.80771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.01428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29724660-vj47x\" ","response":"range_response_count:1 size:7038"} {"level":"warn","ts":"2026-07-08T03:00:47.807716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.597255ms","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-08T03:00:47.80773Z","caller":"traceutil/trace.go:171","msg":"trace[477638753] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:21003; }","duration":"666.874672ms","start":"2026-07-08T03:00:47.140847Z","end":"2026-07-08T03:00:47.807722Z","steps":["trace[477638753] 'agreement among raft nodes before linearized reading' (duration: 666.646425ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:00:47.807743Z","caller":"traceutil/trace.go:171","msg":"trace[2038713226] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29724660-vj47x; range_end:; response_count:1; response_revision:21003; }","duration":"190.082992ms","start":"2026-07-08T03:00:47.617651Z","end":"2026-07-08T03:00:47.807734Z","steps":["trace[2038713226] 'agreement among raft nodes before linearized reading' (duration: 189.990669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.807756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.140833Z","time spent":"666.916283ms","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":193,"response size":33,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-07-08T03:00:47.807754Z","caller":"traceutil/trace.go:171","msg":"trace[317504647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21003; }","duration":"656.652766ms","start":"2026-07-08T03:00:47.151091Z","end":"2026-07-08T03:00:47.807744Z","steps":["trace[317504647] 'agreement among raft nodes before linearized reading' (duration: 656.545373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.808623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.151081Z","time spent":"657.523451ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T03:00:47.807705Z","caller":"traceutil/trace.go:171","msg":"trace[809350955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21003; }","duration":"651.291327ms","start":"2026-07-08T03:00:47.156406Z","end":"2026-07-08T03:00:47.807697Z","steps":["trace[809350955] 'agreement among raft nodes before linearized reading' (duration: 651.223715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.808872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.15636Z","time spent":"652.496601ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T03:00:47.807857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.617107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T03:00:47.809098Z","caller":"traceutil/trace.go:171","msg":"trace[1077059322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21003; }","duration":"666.920344ms","start":"2026-07-08T03:00:47.142162Z","end":"2026-07-08T03:00:47.809083Z","steps":["trace[1077059322] 'agreement among raft nodes before linearized reading' (duration: 665.518815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.142147Z","time spent":"666.979705ms","remote":"127.0.0.1:42870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T03:00:47.808436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.782155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:00:47.80926Z","caller":"traceutil/trace.go:171","msg":"trace[314641326] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:21003; }","duration":"218.618458ms","start":"2026-07-08T03:00:47.590577Z","end":"2026-07-08T03:00:47.809196Z","steps":["trace[314641326] 'agreement among raft nodes before linearized reading' (duration: 216.416976ms)"],"step_count":1}