{"level":"warn","ts":"2026-07-07T16:04:56.919597Z","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-07T16:04:56.919726Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-07T16:04:56.919809Z","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-07T16:04:56.919824Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-07T16:04:56.919847Z","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-07T16:04:56.920706Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-07-07T16:04:56.920797Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-07T16:04:56.937632Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.440298ms"} {"level":"info","ts":"2026-07-07T16:04:56.95955Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-07-07T16:04:56.959663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T16:04:56.95969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-07-07T16:04:56.959702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T16:04:56.95973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-07-07T16:04:56.959775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-07-07T16:04:57.01033Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T16:04:57.016448Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T16:04:57.021987Z","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-07T16:04:57.029265Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T16:04:57.029443Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T16:04:57.029539Z","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-07T16:04:57.029687Z","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-07T16:04:57.0297Z","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-07T16:04:57.031643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-07-07T16:04:57.031746Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-07T16:04:57.039506Z","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-07T16:04:57.039783Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T16:04:57.039819Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T16:04:57.039923Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-07T16:04:57.039941Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-07T16:04:57.161261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T16:04:57.161355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T16:04:57.161391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-07-07T16:04:57.161409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-07-07T16:04:57.161418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-07T16:04:57.161429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-07-07T16:04:57.16144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-07T16:04:57.165979Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T16:04:57.171147Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T16:04:57.171151Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T16:04:57.171224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T16:04:57.171659Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T16:04:57.171785Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T16:04:57.172732Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T16:04:57.172957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T16:04:57.173033Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T16:04:57.175052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-07-07T16:04:57.176136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T16:05:00.098836Z","caller":"traceutil/trace.go:171","msg":"trace[840270222] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"128.50945ms","start":"2026-07-07T16:04:59.970299Z","end":"2026-07-07T16:05:00.098809Z","steps":["trace[840270222] 'process raft request' (duration: 64.46109ms)","trace[840270222] 'compare' (duration: 63.902376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:05:06.024703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.499863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-07T16:05:06.024915Z","caller":"traceutil/trace.go:171","msg":"trace[755724919] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"206.75888ms","start":"2026-07-07T16:05:05.818132Z","end":"2026-07-07T16:05:06.024891Z","steps":["trace[755724919] 'range keys from in-memory index tree' (duration: 206.36404ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:05:06.177281Z","caller":"traceutil/trace.go:171","msg":"trace[1585493262] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"148.82967ms","start":"2026-07-07T16:05:06.028429Z","end":"2026-07-07T16:05:06.177259Z","steps":["trace[1585493262] 'process raft request' (duration: 148.697326ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:05:06.411197Z","caller":"traceutil/trace.go:171","msg":"trace[2057787928] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"226.392792ms","start":"2026-07-07T16:05:06.184782Z","end":"2026-07-07T16:05:06.411175Z","steps":["trace[2057787928] 'process raft request' (duration: 135.933553ms)","trace[2057787928] 'compare' (duration: 90.346976ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:05:41.968301Z","caller":"traceutil/trace.go:171","msg":"trace[1489943622] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"107.399842ms","start":"2026-07-07T16:05:41.860881Z","end":"2026-07-07T16:05:41.968281Z","steps":["trace[1489943622] 'process raft request' (duration: 102.909269ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:05:48.002713Z","caller":"traceutil/trace.go:171","msg":"trace[164452971] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"181.933681ms","start":"2026-07-07T16:05:47.820754Z","end":"2026-07-07T16:05:48.002688Z","steps":["trace[164452971] 'process raft request' (duration: 171.345014ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:05:48.217115Z","caller":"traceutil/trace.go:171","msg":"trace[1838164442] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"162.014717ms","start":"2026-07-07T16:05:48.055062Z","end":"2026-07-07T16:05:48.217077Z","steps":["trace[1838164442] 'process raft request' (duration: 110.049279ms)","trace[1838164442] 'compare' (duration: 51.677391ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:05:48.823696Z","caller":"traceutil/trace.go:171","msg":"trace[919145004] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"192.678883ms","start":"2026-07-07T16:05:48.630998Z","end":"2026-07-07T16:05:48.823677Z","steps":["trace[919145004] 'process raft request' (duration: 191.934454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:05:49.418398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.931306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:05:49.418597Z","caller":"traceutil/trace.go:171","msg":"trace[432599803] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"377.450676ms","start":"2026-07-07T16:05:49.041129Z","end":"2026-07-07T16:05:49.41858Z","steps":["trace[432599803] 'read index received' (duration: 19.839702ms)","trace[432599803] 'applied index is now lower than readState.Index' (duration: 357.610094ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:05:49.418605Z","caller":"traceutil/trace.go:171","msg":"trace[275452371] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"589.572133ms","start":"2026-07-07T16:05:48.829002Z","end":"2026-07-07T16:05:49.418574Z","steps":["trace[275452371] 'process raft request' (duration: 231.806675ms)","trace[275452371] 'compare' (duration: 356.793943ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:05:49.418767Z","caller":"traceutil/trace.go:171","msg":"trace[861309373] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"529.401338ms","start":"2026-07-07T16:05:48.889299Z","end":"2026-07-07T16:05:49.4187Z","steps":["trace[861309373] 'process raft request' (duration: 529.209194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:05:49.418824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.685593ms","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-07T16:05:49.418882Z","caller":"traceutil/trace.go:171","msg":"trace[1790170999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:613; }","duration":"377.768305ms","start":"2026-07-07T16:05:49.041099Z","end":"2026-07-07T16:05:49.418867Z","steps":["trace[1790170999] 'agreement among raft nodes before linearized reading' (duration: 377.57299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:05:49.418908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:05:48.828993Z","time spent":"589.786269ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:05:49.418948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:05:49.041084Z","time spent":"377.852787ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T16:05:49.418946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:05:48.889275Z","time spent":"529.596973ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T16:05:49.418937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.851407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:05:49.419053Z","caller":"traceutil/trace.go:171","msg":"trace[67673741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"281.040413ms","start":"2026-07-07T16:05:49.137988Z","end":"2026-07-07T16:05:49.419028Z","steps":["trace[67673741] 'agreement among raft nodes before linearized reading' (duration: 280.860788ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:06:59.299513Z","caller":"traceutil/trace.go:171","msg":"trace[1515059721] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"109.998914ms","start":"2026-07-07T16:06:59.189492Z","end":"2026-07-07T16:06:59.299491Z","steps":["trace[1515059721] 'process raft request' (duration: 109.747428ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:07:24.076723Z","caller":"traceutil/trace.go:171","msg":"trace[920548437] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"214.927439ms","start":"2026-07-07T16:07:23.861749Z","end":"2026-07-07T16:07:24.076677Z","steps":["trace[920548437] 'process raft request' (duration: 214.684832ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:07:24.099152Z","caller":"traceutil/trace.go:171","msg":"trace[735576925] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1934; }","duration":"195.049811ms","start":"2026-07-07T16:07:23.904079Z","end":"2026-07-07T16:07:24.099129Z","steps":["trace[735576925] 'read index received' (duration: 172.408771ms)","trace[735576925] 'applied index is now lower than readState.Index' (duration: 22.64042ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:07:24.099362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.257516ms","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-07T16:07:24.099406Z","caller":"traceutil/trace.go:171","msg":"trace[1366300478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1861; }","duration":"195.354969ms","start":"2026-07-07T16:07:23.90404Z","end":"2026-07-07T16:07:24.099395Z","steps":["trace[1366300478] 'agreement among raft nodes before linearized reading' (duration: 195.200825ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:07:24.099343Z","caller":"traceutil/trace.go:171","msg":"trace[1142678975] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"208.390613ms","start":"2026-07-07T16:07:23.890888Z","end":"2026-07-07T16:07:24.099279Z","steps":["trace[1142678975] 'process raft request' (duration: 208.116975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:07:24.099614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.79863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-07T16:07:24.099699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.551273ms","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-07T16:07:24.099765Z","caller":"traceutil/trace.go:171","msg":"trace[464357747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1861; }","duration":"100.674867ms","start":"2026-07-07T16:07:23.999074Z","end":"2026-07-07T16:07:24.099749Z","steps":["trace[464357747] 'agreement among raft nodes before linearized reading' (duration: 100.372789ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:07:24.099731Z","caller":"traceutil/trace.go:171","msg":"trace[1526314907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1861; }","duration":"179.964905ms","start":"2026-07-07T16:07:23.919742Z","end":"2026-07-07T16:07:24.099707Z","steps":["trace[1526314907] 'agreement among raft nodes before linearized reading' (duration: 179.621506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:07:24.577122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.502477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:07:24.577247Z","caller":"traceutil/trace.go:171","msg":"trace[241683157] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"199.050223ms","start":"2026-07-07T16:07:24.378176Z","end":"2026-07-07T16:07:24.577227Z","steps":["trace[241683157] 'read index received' (duration: 4.168067ms)","trace[241683157] 'applied index is now lower than readState.Index' (duration: 194.880216ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:07:24.577571Z","caller":"traceutil/trace.go:171","msg":"trace[19827244] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"297.722897ms","start":"2026-07-07T16:07:24.279582Z","end":"2026-07-07T16:07:24.577305Z","steps":["trace[19827244] 'process raft request' (duration: 102.820431ms)","trace[19827244] 'compare' (duration: 194.377373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:07:24.577659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.482284ms","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-07T16:07:24.577707Z","caller":"traceutil/trace.go:171","msg":"trace[83891160] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1866; }","duration":"199.562996ms","start":"2026-07-07T16:07:24.37813Z","end":"2026-07-07T16:07:24.577693Z","steps":["trace[83891160] 'agreement among raft nodes before linearized reading' (duration: 199.155086ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:07:42.337757Z","caller":"traceutil/trace.go:171","msg":"trace[1458704834] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"180.778658ms","start":"2026-07-07T16:07:42.156894Z","end":"2026-07-07T16:07:42.337673Z","steps":["trace[1458704834] 'process raft request' (duration: 172.839261ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:07:42.33911Z","caller":"traceutil/trace.go:171","msg":"trace[1801190324] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"127.947429ms","start":"2026-07-07T16:07:42.211133Z","end":"2026-07-07T16:07:42.33908Z","steps":["trace[1801190324] 'process raft request' (duration: 127.749404ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:08:24.646846Z","caller":"traceutil/trace.go:171","msg":"trace[773603861] linearizableReadLoop","detail":"{readStateIndex:2325; appliedIndex:2324; }","duration":"109.323548ms","start":"2026-07-07T16:08:24.537486Z","end":"2026-07-07T16:08:24.646809Z","steps":["trace[773603861] 'read index received' (duration: 108.99569ms)","trace[773603861] 'applied index is now lower than readState.Index' (duration: 325.918µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:08:24.647002Z","caller":"traceutil/trace.go:171","msg":"trace[1411606280] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"134.706676ms","start":"2026-07-07T16:08:24.512259Z","end":"2026-07-07T16:08:24.646966Z","steps":["trace[1411606280] 'process raft request' (duration: 134.221264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:08:24.647221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.706248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-07T16:08:24.647266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.684508ms","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-07T16:08:24.647282Z","caller":"traceutil/trace.go:171","msg":"trace[1387778602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2237; }","duration":"109.8103ms","start":"2026-07-07T16:08:24.537452Z","end":"2026-07-07T16:08:24.647263Z","steps":["trace[1387778602] 'agreement among raft nodes before linearized reading' (duration: 109.568365ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:08:24.647323Z","caller":"traceutil/trace.go:171","msg":"trace[1365311099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2237; }","duration":"105.749139ms","start":"2026-07-07T16:08:24.541557Z","end":"2026-07-07T16:08:24.647306Z","steps":["trace[1365311099] 'agreement among raft nodes before linearized reading' (duration: 105.590766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:08:24.647259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.900247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T16:08:24.647416Z","caller":"traceutil/trace.go:171","msg":"trace[516294718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2237; }","duration":"107.121333ms","start":"2026-07-07T16:08:24.54029Z","end":"2026-07-07T16:08:24.647411Z","steps":["trace[516294718] 'agreement among raft nodes before linearized reading' (duration: 106.806035ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:08:24.842139Z","caller":"traceutil/trace.go:171","msg":"trace[1655109239] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"190.691023ms","start":"2026-07-07T16:08:24.651428Z","end":"2026-07-07T16:08:24.842119Z","steps":["trace[1655109239] 'process raft request' (duration: 127.098958ms)","trace[1655109239] 'compare' (duration: 63.449202ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:08:24.842334Z","caller":"traceutil/trace.go:171","msg":"trace[569140790] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"190.522379ms","start":"2026-07-07T16:08:24.6518Z","end":"2026-07-07T16:08:24.842322Z","steps":["trace[569140790] 'process raft request' (duration: 190.290913ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:08:24.842573Z","caller":"traceutil/trace.go:171","msg":"trace[589767890] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"190.556649ms","start":"2026-07-07T16:08:24.651986Z","end":"2026-07-07T16:08:24.842543Z","steps":["trace[589767890] 'process raft request' (duration: 190.275633ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:13:42.514717Z","caller":"traceutil/trace.go:171","msg":"trace[1015942553] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"109.083427ms","start":"2026-07-07T16:13:42.405601Z","end":"2026-07-07T16:13:42.514685Z","steps":["trace[1015942553] 'process raft request' (duration: 108.755179ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:13:42.515392Z","caller":"traceutil/trace.go:171","msg":"trace[634912788] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"108.612608ms","start":"2026-07-07T16:13:42.406739Z","end":"2026-07-07T16:13:42.515352Z","steps":["trace[634912788] 'process raft request' (duration: 107.874329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:13:42.515491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.36829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-07T16:13:42.515585Z","caller":"traceutil/trace.go:171","msg":"trace[1601204547] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4832; }","duration":"118.522015ms","start":"2026-07-07T16:13:42.397043Z","end":"2026-07-07T16:13:42.515565Z","steps":["trace[1601204547] 'agreement among raft nodes before linearized reading' (duration: 118.244418ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:13:42.783682Z","caller":"traceutil/trace.go:171","msg":"trace[451704838] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"133.300578ms","start":"2026-07-07T16:13:42.650362Z","end":"2026-07-07T16:13:42.783663Z","steps":["trace[451704838] 'process raft request' (duration: 133.164783ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:13:45.348066Z","caller":"traceutil/trace.go:171","msg":"trace[972906729] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"119.16849ms","start":"2026-07-07T16:13:45.228875Z","end":"2026-07-07T16:13:45.348043Z","steps":["trace[972906729] 'process raft request' (duration: 101.252482ms)","trace[972906729] 'compare' (duration: 17.5751ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:14:09.008745Z","caller":"traceutil/trace.go:171","msg":"trace[1298785792] transaction","detail":"{read_only:false; response_revision:5530; number_of_response:1; }","duration":"121.42185ms","start":"2026-07-07T16:14:08.887294Z","end":"2026-07-07T16:14:09.008715Z","steps":["trace[1298785792] 'process raft request' (duration: 120.871606ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:14:09.241052Z","caller":"traceutil/trace.go:171","msg":"trace[805501121] linearizableReadLoop","detail":"{readStateIndex:5739; appliedIndex:5738; }","duration":"104.0751ms","start":"2026-07-07T16:14:09.13696Z","end":"2026-07-07T16:14:09.241035Z","steps":["trace[805501121] 'read index received' (duration: 103.970507ms)","trace[805501121] 'applied index is now lower than readState.Index' (duration: 103.943µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:14:09.241136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.167262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:14:09.241171Z","caller":"traceutil/trace.go:171","msg":"trace[1592983721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5531; }","duration":"104.240994ms","start":"2026-07-07T16:14:09.136922Z","end":"2026-07-07T16:14:09.241163Z","steps":["trace[1592983721] 'agreement among raft nodes before linearized reading' (duration: 104.177472ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:14:09.241304Z","caller":"traceutil/trace.go:171","msg":"trace[916035932] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"119.041662ms","start":"2026-07-07T16:14:09.122244Z","end":"2026-07-07T16:14:09.241286Z","steps":["trace[916035932] 'process raft request' (duration: 118.697354ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:14:09.630563Z","caller":"traceutil/trace.go:171","msg":"trace[2029378320] linearizableReadLoop","detail":"{readStateIndex:5740; appliedIndex:5739; }","duration":"153.528708ms","start":"2026-07-07T16:14:09.477009Z","end":"2026-07-07T16:14:09.630538Z","steps":["trace[2029378320] 'read index received' (duration: 153.375334ms)","trace[2029378320] 'applied index is now lower than readState.Index' (duration: 151.704µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:14:09.630652Z","caller":"traceutil/trace.go:171","msg":"trace[725877763] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"155.364951ms","start":"2026-07-07T16:14:09.475244Z","end":"2026-07-07T16:14:09.630609Z","steps":["trace[725877763] 'process raft request' (duration: 155.136216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:14:09.630796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.767183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-07T16:14:09.630855Z","caller":"traceutil/trace.go:171","msg":"trace[1204284871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5532; }","duration":"153.849896ms","start":"2026-07-07T16:14:09.476981Z","end":"2026-07-07T16:14:09.630831Z","steps":["trace[1204284871] 'agreement among raft nodes before linearized reading' (duration: 153.681012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:14:09.64026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.720626ms","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-07T16:14:09.640349Z","caller":"traceutil/trace.go:171","msg":"trace[615183524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5533; }","duration":"123.867919ms","start":"2026-07-07T16:14:09.516463Z","end":"2026-07-07T16:14:09.640331Z","steps":["trace[615183524] 'agreement among raft nodes before linearized reading' (duration: 123.639044ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:14:57.478199Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2938} {"level":"info","ts":"2026-07-07T16:14:57.555551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2938,"took":"75.068088ms","hash":2719784884,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":33755136,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-07T16:14:57.555625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2719784884,"revision":2938,"compact-revision":-1} {"level":"info","ts":"2026-07-07T16:15:01.861184Z","caller":"traceutil/trace.go:171","msg":"trace[1373730496] transaction","detail":"{read_only:false; response_revision:6005; number_of_response:1; }","duration":"102.888422ms","start":"2026-07-07T16:15:01.758265Z","end":"2026-07-07T16:15:01.861153Z","steps":["trace[1373730496] 'process raft request' (duration: 102.684177ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:19:57.486065Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5974} {"level":"info","ts":"2026-07-07T16:19:57.592449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5974,"took":"104.217345ms","hash":638462996,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":21893120,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-07T16:19:57.592552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":638462996,"revision":5974,"compact-revision":2938} {"level":"warn","ts":"2026-07-07T16:20:39.923747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.334296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.923854Z","caller":"traceutil/trace.go:171","msg":"trace[102286946] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8450; }","duration":"185.503441ms","start":"2026-07-07T16:20:39.738333Z","end":"2026-07-07T16:20:39.923837Z","steps":["trace[102286946] 'range keys from in-memory index tree' (duration: 185.202342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.924078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.363487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.924115Z","caller":"traceutil/trace.go:171","msg":"trace[1330271346] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8450; }","duration":"185.451329ms","start":"2026-07-07T16:20:39.738654Z","end":"2026-07-07T16:20:39.924105Z","steps":["trace[1330271346] 'count revisions from in-memory index tree' (duration: 185.218093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.924206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.164782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:20:39.924254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.17881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.924267Z","caller":"traceutil/trace.go:171","msg":"trace[553297756] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8450; }","duration":"162.261104ms","start":"2026-07-07T16:20:39.761992Z","end":"2026-07-07T16:20:39.924254Z","steps":["trace[553297756] 'range keys from in-memory index tree' (duration: 162.116181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.924268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.688494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.924285Z","caller":"traceutil/trace.go:171","msg":"trace[1150300233] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8450; }","duration":"116.243602ms","start":"2026-07-07T16:20:39.808033Z","end":"2026-07-07T16:20:39.924277Z","steps":["trace[1150300233] 'range keys from in-memory index tree' (duration: 116.121759ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:39.924311Z","caller":"traceutil/trace.go:171","msg":"trace[857180268] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8450; }","duration":"116.776326ms","start":"2026-07-07T16:20:39.807522Z","end":"2026-07-07T16:20:39.924298Z","steps":["trace[857180268] 'range keys from in-memory index tree' (duration: 116.604832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.924293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.662455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:20:39.924347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.714105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:20:39.924373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.104715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.924395Z","caller":"traceutil/trace.go:171","msg":"trace[1238758191] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8450; }","duration":"135.833499ms","start":"2026-07-07T16:20:39.788543Z","end":"2026-07-07T16:20:39.924376Z","steps":["trace[1238758191] 'range keys from in-memory index tree' (duration: 135.49929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.924432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.650144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.924425Z","caller":"traceutil/trace.go:171","msg":"trace[1047268965] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8450; }","duration":"135.836268ms","start":"2026-07-07T16:20:39.78857Z","end":"2026-07-07T16:20:39.924406Z","steps":["trace[1047268965] 'count revisions from in-memory index tree' (duration: 135.638513ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:39.924452Z","caller":"traceutil/trace.go:171","msg":"trace[158497293] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8450; }","duration":"163.231578ms","start":"2026-07-07T16:20:39.761203Z","end":"2026-07-07T16:20:39.924435Z","steps":["trace[158497293] 'count revisions from in-memory index tree' (duration: 163.017702ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:39.924467Z","caller":"traceutil/trace.go:171","msg":"trace[130889275] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8450; }","duration":"185.684235ms","start":"2026-07-07T16:20:39.738774Z","end":"2026-07-07T16:20:39.924458Z","steps":["trace[130889275] 'range keys from in-memory index tree' (duration: 185.559612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.924444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.128945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.924549Z","caller":"traceutil/trace.go:171","msg":"trace[1501055638] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8450; }","duration":"163.278469ms","start":"2026-07-07T16:20:39.761264Z","end":"2026-07-07T16:20:39.924542Z","steps":["trace[1501055638] 'range keys from in-memory index tree' (duration: 163.056554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.924203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.413578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.924613Z","caller":"traceutil/trace.go:171","msg":"trace[1386850604] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:8450; }","duration":"116.883278ms","start":"2026-07-07T16:20:39.807722Z","end":"2026-07-07T16:20:39.924605Z","steps":["trace[1386850604] 'count revisions from in-memory index tree' (duration: 116.330386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:39.925987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.717781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:39.926285Z","caller":"traceutil/trace.go:171","msg":"trace[1134679093] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8450; }","duration":"136.736021ms","start":"2026-07-07T16:20:39.789539Z","end":"2026-07-07T16:20:39.926275Z","steps":["trace[1134679093] 'range keys from in-memory index tree' (duration: 134.606358ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:40.990875Z","caller":"traceutil/trace.go:171","msg":"trace[1766397657] linearizableReadLoop","detail":"{readStateIndex:8758; appliedIndex:8757; }","duration":"140.577536ms","start":"2026-07-07T16:20:40.850278Z","end":"2026-07-07T16:20:40.990855Z","steps":["trace[1766397657] 'read index received' (duration: 140.522625ms)","trace[1766397657] 'applied index is now lower than readState.Index' (duration: 53.921µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:20:40.990964Z","caller":"traceutil/trace.go:171","msg":"trace[1557844241] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"173.401441ms","start":"2026-07-07T16:20:40.817554Z","end":"2026-07-07T16:20:40.990956Z","steps":["trace[1557844241] 'process raft request' (duration: 173.159195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:40.9911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.812862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-07T16:20:40.991152Z","caller":"traceutil/trace.go:171","msg":"trace[590836526] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:8454; }","duration":"140.901444ms","start":"2026-07-07T16:20:40.850238Z","end":"2026-07-07T16:20:40.991139Z","steps":["trace[590836526] 'agreement among raft nodes before linearized reading' (duration: 140.813492ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:41.172237Z","caller":"traceutil/trace.go:171","msg":"trace[656449172] linearizableReadLoop","detail":"{readStateIndex:8759; appliedIndex:8758; }","duration":"181.268986ms","start":"2026-07-07T16:20:40.990931Z","end":"2026-07-07T16:20:41.1722Z","steps":["trace[656449172] 'read index received' (duration: 115.304789ms)","trace[656449172] 'applied index is now lower than readState.Index' (duration: 65.962827ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:20:41.172299Z","caller":"traceutil/trace.go:171","msg":"trace[1431422165] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"299.913788ms","start":"2026-07-07T16:20:40.87235Z","end":"2026-07-07T16:20:41.172264Z","steps":["trace[1431422165] 'process raft request' (duration: 233.736267ms)","trace[1431422165] 'compare' (duration: 65.902894ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:20:41.172449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:20:40.87233Z","time spent":"300.034382ms","remote":"127.0.0.1:43036","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-07T16:20:41.172525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.05785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T16:20:41.172594Z","caller":"traceutil/trace.go:171","msg":"trace[776320312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8455; }","duration":"235.179053ms","start":"2026-07-07T16:20:40.937395Z","end":"2026-07-07T16:20:41.172574Z","steps":["trace[776320312] 'agreement among raft nodes before linearized reading' (duration: 234.937847ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:41.209047Z","caller":"traceutil/trace.go:171","msg":"trace[2093814426] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"185.601444ms","start":"2026-07-07T16:20:41.02342Z","end":"2026-07-07T16:20:41.209021Z","steps":["trace[2093814426] 'process raft request' (duration: 185.47283ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:41.235511Z","caller":"traceutil/trace.go:171","msg":"trace[1483873266] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"120.029957ms","start":"2026-07-07T16:20:41.115456Z","end":"2026-07-07T16:20:41.235486Z","steps":["trace[1483873266] 'process raft request' (duration: 119.731729ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:41.39062Z","caller":"traceutil/trace.go:171","msg":"trace[1799771965] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"180.441305ms","start":"2026-07-07T16:20:41.210147Z","end":"2026-07-07T16:20:41.390589Z","steps":["trace[1799771965] 'process raft request' (duration: 85.88076ms)","trace[1799771965] 'compare' (duration: 94.331769ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:20:41.390686Z","caller":"traceutil/trace.go:171","msg":"trace[137199105] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"175.761079ms","start":"2026-07-07T16:20:41.214901Z","end":"2026-07-07T16:20:41.390662Z","steps":["trace[137199105] 'process raft request' (duration: 175.631455ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:41.391007Z","caller":"traceutil/trace.go:171","msg":"trace[82031945] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"113.412393ms","start":"2026-07-07T16:20:41.277554Z","end":"2026-07-07T16:20:41.390966Z","steps":["trace[82031945] 'process raft request' (duration: 113.064323ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:41.784144Z","caller":"traceutil/trace.go:171","msg":"trace[447176350] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"120.382736ms","start":"2026-07-07T16:20:41.663737Z","end":"2026-07-07T16:20:41.784119Z","steps":["trace[447176350] 'process raft request' (duration: 120.241602ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:42.079311Z","caller":"traceutil/trace.go:171","msg":"trace[193031324] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8766; }","duration":"397.19811ms","start":"2026-07-07T16:20:41.682071Z","end":"2026-07-07T16:20:42.079269Z","steps":["trace[193031324] 'read index received' (duration: 101.965879ms)","trace[193031324] 'applied index is now lower than readState.Index' (duration: 295.230891ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:20:42.079319Z","caller":"traceutil/trace.go:171","msg":"trace[103311190] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"407.30714ms","start":"2026-07-07T16:20:41.671984Z","end":"2026-07-07T16:20:42.079291Z","steps":["trace[103311190] 'process raft request' (duration: 389.618482ms)","trace[103311190] 'compare' (duration: 17.374041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:20:42.07959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.490267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-07T16:20:42.079688Z","caller":"traceutil/trace.go:171","msg":"trace[776922460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8463; }","duration":"397.659192ms","start":"2026-07-07T16:20:41.682004Z","end":"2026-07-07T16:20:42.079663Z","steps":["trace[776922460] 'agreement among raft nodes before linearized reading' (duration: 397.370804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:42.079742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:20:41.681987Z","time spent":"397.743963ms","remote":"127.0.0.1:43036","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-07T16:20:42.079666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:20:41.671962Z","time spent":"407.513326ms","remote":"127.0.0.1:43036","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-07T16:20:42.103026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.742631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:20:42.103087Z","caller":"traceutil/trace.go:171","msg":"trace[1168164245] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8464; }","duration":"317.865264ms","start":"2026-07-07T16:20:41.785207Z","end":"2026-07-07T16:20:42.103073Z","steps":["trace[1168164245] 'agreement among raft nodes before linearized reading' (duration: 317.73542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:42.103123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:20:41.785187Z","time spent":"317.925155ms","remote":"127.0.0.1:58954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-07T16:20:42.103098Z","caller":"traceutil/trace.go:171","msg":"trace[1676148083] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"239.775807ms","start":"2026-07-07T16:20:41.863267Z","end":"2026-07-07T16:20:42.103042Z","steps":["trace[1676148083] 'process raft request' (duration: 239.560722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:20:42.103197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.563642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:20:42.103233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.271634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b4lv9\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-07T16:20:42.103306Z","caller":"traceutil/trace.go:171","msg":"trace[1608574959] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b4lv9; range_end:; response_count:1; response_revision:8464; }","duration":"162.377787ms","start":"2026-07-07T16:20:41.940913Z","end":"2026-07-07T16:20:42.103291Z","steps":["trace[1608574959] 'agreement among raft nodes before linearized reading' (duration: 162.197072ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:20:42.103257Z","caller":"traceutil/trace.go:171","msg":"trace[1517034072] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8464; }","duration":"233.650045ms","start":"2026-07-07T16:20:41.869594Z","end":"2026-07-07T16:20:42.103244Z","steps":["trace[1517034072] 'agreement among raft nodes before linearized reading' (duration: 233.545552ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:21:04.999518Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022e7.wal"} {"level":"warn","ts":"2026-07-07T16:21:09.842247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.303955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-798b5c6d59-4k2xz.18c00e570b4e1f38\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-07-07T16:21:09.842335Z","caller":"traceutil/trace.go:171","msg":"trace[872714927] range","detail":"{range_begin:/registry/events/openstack/barbican-api-798b5c6d59-4k2xz.18c00e570b4e1f38; range_end:; response_count:1; response_revision:8662; }","duration":"387.403337ms","start":"2026-07-07T16:21:09.454913Z","end":"2026-07-07T16:21:09.842316Z","steps":["trace[872714927] 'range keys from in-memory index tree' (duration: 387.08191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:21:09.842376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:21:09.454892Z","time spent":"387.473679ms","remote":"127.0.0.1:42838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1158,"request content":"key:\"/registry/events/openstack/barbican-api-798b5c6d59-4k2xz.18c00e570b4e1f38\" "} {"level":"info","ts":"2026-07-07T16:21:09.842425Z","caller":"traceutil/trace.go:171","msg":"trace[360834553] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"367.280448ms","start":"2026-07-07T16:21:09.475126Z","end":"2026-07-07T16:21:09.842407Z","steps":["trace[360834553] 'process raft request' (duration: 343.062137ms)","trace[360834553] 'compare' (duration: 23.668888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:21:09.842524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:21:09.475105Z","time spent":"367.35239ms","remote":"127.0.0.1:43036","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-07T16:21:13.259929Z","caller":"traceutil/trace.go:171","msg":"trace[1415502140] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"115.112655ms","start":"2026-07-07T16:21:13.144788Z","end":"2026-07-07T16:21:13.259901Z","steps":["trace[1415502140] 'process raft request' (duration: 114.90633ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:21:23.008796Z","caller":"traceutil/trace.go:171","msg":"trace[614453606] transaction","detail":"{read_only:false; response_revision:8778; number_of_response:1; }","duration":"133.790438ms","start":"2026-07-07T16:21:22.874977Z","end":"2026-07-07T16:21:23.008767Z","steps":["trace[614453606] 'process raft request' (duration: 97.234942ms)","trace[614453606] 'compare' (duration: 36.342131ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:21:23.021993Z","caller":"traceutil/trace.go:171","msg":"trace[403556873] transaction","detail":"{read_only:false; response_revision:8779; number_of_response:1; }","duration":"141.09955ms","start":"2026-07-07T16:21:22.880868Z","end":"2026-07-07T16:21:23.021968Z","steps":["trace[403556873] 'process raft request' (duration: 140.988186ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:22:35.942841Z","caller":"traceutil/trace.go:171","msg":"trace[117107902] linearizableReadLoop","detail":"{readStateIndex:9846; appliedIndex:9845; }","duration":"116.446218ms","start":"2026-07-07T16:22:35.826374Z","end":"2026-07-07T16:22:35.94282Z","steps":["trace[117107902] 'read index received' (duration: 100.845821ms)","trace[117107902] 'applied index is now lower than readState.Index' (duration: 15.599707ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:22:35.943192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.805197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T16:22:35.943154Z","caller":"traceutil/trace.go:171","msg":"trace[793336690] transaction","detail":"{read_only:false; response_revision:9510; number_of_response:1; }","duration":"227.707156ms","start":"2026-07-07T16:22:35.715407Z","end":"2026-07-07T16:22:35.943114Z","steps":["trace[793336690] 'process raft request' (duration: 211.868303ms)","trace[793336690] 'compare' (duration: 15.265709ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:22:35.943224Z","caller":"traceutil/trace.go:171","msg":"trace[419008930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9510; }","duration":"116.883619ms","start":"2026-07-07T16:22:35.826334Z","end":"2026-07-07T16:22:35.943217Z","steps":["trace[419008930] 'agreement among raft nodes before linearized reading' (duration: 116.567001ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:22:54.543779Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-07T16:22:54.549792Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-07T16:22:54.549892Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-07T16:24:57.4931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8054} {"level":"info","ts":"2026-07-07T16:24:57.556667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8054,"took":"61.491905ms","hash":749314725,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":28323840,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-07T16:24:57.556723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":749314725,"revision":8054,"compact-revision":5974} {"level":"warn","ts":"2026-07-07T16:26:44.414486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.36803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:26:44.414575Z","caller":"traceutil/trace.go:171","msg":"trace[383130995] linearizableReadLoop","detail":"{readStateIndex:11506; appliedIndex:11505; }","duration":"400.044337ms","start":"2026-07-07T16:26:44.014519Z","end":"2026-07-07T16:26:44.414563Z","steps":["trace[383130995] 'read index received' (duration: 92.535963ms)","trace[383130995] 'applied index is now lower than readState.Index' (duration: 307.507594ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:26:44.414652Z","caller":"traceutil/trace.go:171","msg":"trace[626222967] transaction","detail":"{read_only:false; response_revision:11113; number_of_response:1; }","duration":"519.805317ms","start":"2026-07-07T16:26:43.894839Z","end":"2026-07-07T16:26:44.414644Z","steps":["trace[626222967] 'process raft request' (duration: 212.213521ms)","trace[626222967] 'compare' (duration: 307.288009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:26:44.414693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:26:43.894821Z","time spent":"519.853039ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:26:44.415354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.386422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:26:44.4154Z","caller":"traceutil/trace.go:171","msg":"trace[394165874] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:11113; }","duration":"388.681039ms","start":"2026-07-07T16:26:44.026706Z","end":"2026-07-07T16:26:44.415387Z","steps":["trace[394165874] 'agreement among raft nodes before linearized reading' (duration: 388.404252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:26:44.415427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:26:44.026676Z","time spent":"388.744141ms","remote":"127.0.0.1:43150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-07-07T16:26:44.415482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.948819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:414695"} {"level":"info","ts":"2026-07-07T16:26:44.41556Z","caller":"traceutil/trace.go:171","msg":"trace[1004903709] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:11113; }","duration":"401.081563ms","start":"2026-07-07T16:26:44.014458Z","end":"2026-07-07T16:26:44.415539Z","steps":["trace[1004903709] 'agreement among raft nodes before linearized reading' (duration: 400.609681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:26:44.415599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:26:44.014426Z","time spent":"401.164625ms","remote":"127.0.0.1:53486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":414719,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-07-07T16:28:09.418568Z","caller":"traceutil/trace.go:171","msg":"trace[1114655814] linearizableReadLoop","detail":"{readStateIndex:12330; appliedIndex:12329; }","duration":"126.844169ms","start":"2026-07-07T16:28:09.291704Z","end":"2026-07-07T16:28:09.418548Z","steps":["trace[1114655814] 'read index received' (duration: 126.658734ms)","trace[1114655814] 'applied index is now lower than readState.Index' (duration: 184.775µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:28:09.418638Z","caller":"traceutil/trace.go:171","msg":"trace[119491290] transaction","detail":"{read_only:false; response_revision:11916; number_of_response:1; }","duration":"201.668688ms","start":"2026-07-07T16:28:09.216934Z","end":"2026-07-07T16:28:09.418603Z","steps":["trace[119491290] 'process raft request' (duration: 201.475963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:28:09.418878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.156376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:28:09.418876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.845068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-87bdf966c-8f87k.18c00ebd0ffcd248\" ","response":"range_response_count:1 size:801"} {"level":"info","ts":"2026-07-07T16:28:09.41896Z","caller":"traceutil/trace.go:171","msg":"trace[1536805735] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-87bdf966c-8f87k.18c00ebd0ffcd248; range_end:; response_count:1; response_revision:11916; }","duration":"124.978591ms","start":"2026-07-07T16:28:09.29396Z","end":"2026-07-07T16:28:09.418939Z","steps":["trace[1536805735] 'agreement among raft nodes before linearized reading' (duration: 124.734515ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:28:09.418973Z","caller":"traceutil/trace.go:171","msg":"trace[132948988] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:11916; }","duration":"127.291169ms","start":"2026-07-07T16:28:09.291639Z","end":"2026-07-07T16:28:09.41893Z","steps":["trace[132948988] 'agreement among raft nodes before linearized reading' (duration: 127.011802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:28:10.427905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.049281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T16:28:10.428063Z","caller":"traceutil/trace.go:171","msg":"trace[178681897] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:11926; }","duration":"158.257286ms","start":"2026-07-07T16:28:10.269785Z","end":"2026-07-07T16:28:10.428042Z","steps":["trace[178681897] 'count revisions from in-memory index tree' (duration: 157.954029ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:29:57.499453Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10461} {"level":"info","ts":"2026-07-07T16:29:57.581443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10461,"took":"78.747292ms","hash":652350473,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":26722304,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-07T16:29:57.581507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":652350473,"revision":10461,"compact-revision":8054} {"level":"info","ts":"2026-07-07T16:32:03.088673Z","caller":"traceutil/trace.go:171","msg":"trace[1700002871] transaction","detail":"{read_only:false; response_revision:13427; number_of_response:1; }","duration":"110.882214ms","start":"2026-07-07T16:32:02.977759Z","end":"2026-07-07T16:32:03.088641Z","steps":["trace[1700002871] 'process raft request' (duration: 110.633928ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:34:12.124353Z","caller":"traceutil/trace.go:171","msg":"trace[2036717664] transaction","detail":"{read_only:false; response_revision:14377; number_of_response:1; }","duration":"124.339976ms","start":"2026-07-07T16:34:11.999986Z","end":"2026-07-07T16:34:12.124326Z","steps":["trace[2036717664] 'process raft request' (duration: 124.162822ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:34:33.675165Z","caller":"traceutil/trace.go:171","msg":"trace[647423085] transaction","detail":"{read_only:false; response_revision:14490; number_of_response:1; }","duration":"221.872641ms","start":"2026-07-07T16:34:33.45326Z","end":"2026-07-07T16:34:33.675133Z","steps":["trace[647423085] 'process raft request' (duration: 221.634966ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:34:33.675362Z","caller":"traceutil/trace.go:171","msg":"trace[1546906748] transaction","detail":"{read_only:false; response_revision:14491; number_of_response:1; }","duration":"222.013445ms","start":"2026-07-07T16:34:33.453282Z","end":"2026-07-07T16:34:33.675295Z","steps":["trace[1546906748] 'process raft request' (duration: 221.7895ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:34:49.318679Z","caller":"traceutil/trace.go:171","msg":"trace[1215612320] linearizableReadLoop","detail":"{readStateIndex:15098; appliedIndex:15097; }","duration":"180.436843ms","start":"2026-07-07T16:34:49.138218Z","end":"2026-07-07T16:34:49.318655Z","steps":["trace[1215612320] 'read index received' (duration: 180.30883ms)","trace[1215612320] 'applied index is now lower than readState.Index' (duration: 127.063µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:34:49.318747Z","caller":"traceutil/trace.go:171","msg":"trace[848379897] transaction","detail":"{read_only:false; response_revision:14588; number_of_response:1; }","duration":"214.258515ms","start":"2026-07-07T16:34:49.104436Z","end":"2026-07-07T16:34:49.318695Z","steps":["trace[848379897] 'process raft request' (duration: 214.067891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:34:49.318842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.607937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:34:49.318913Z","caller":"traceutil/trace.go:171","msg":"trace[2059533809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14588; }","duration":"180.687959ms","start":"2026-07-07T16:34:49.1382Z","end":"2026-07-07T16:34:49.318888Z","steps":["trace[2059533809] 'agreement among raft nodes before linearized reading' (duration: 180.574006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:34:49.319177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.498445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:34:49.319297Z","caller":"traceutil/trace.go:171","msg":"trace[1825715663] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:14588; }","duration":"110.834822ms","start":"2026-07-07T16:34:49.208433Z","end":"2026-07-07T16:34:49.319268Z","steps":["trace[1825715663] 'agreement among raft nodes before linearized reading' (duration: 110.450443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:34:49.319279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.892228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-kwf4j\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-07-07T16:34:49.319469Z","caller":"traceutil/trace.go:171","msg":"trace[88888690] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-kwf4j; range_end:; response_count:1; response_revision:14588; }","duration":"136.109824ms","start":"2026-07-07T16:34:49.183342Z","end":"2026-07-07T16:34:49.319452Z","steps":["trace[88888690] 'agreement among raft nodes before linearized reading' (duration: 135.445658ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:34:49.488904Z","caller":"traceutil/trace.go:171","msg":"trace[857096494] transaction","detail":"{read_only:false; response_revision:14589; number_of_response:1; }","duration":"159.732404ms","start":"2026-07-07T16:34:49.329143Z","end":"2026-07-07T16:34:49.488875Z","steps":["trace[857096494] 'process raft request' (duration: 159.56778ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:34:57.50619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12657} {"level":"info","ts":"2026-07-07T16:34:57.591119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12657,"took":"82.731634ms","hash":3313051289,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":28213248,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-07T16:34:57.591191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3313051289,"revision":12657,"compact-revision":10461} {"level":"info","ts":"2026-07-07T16:34:58.528912Z","caller":"traceutil/trace.go:171","msg":"trace[131459191] transaction","detail":"{read_only:false; response_revision:14756; number_of_response:1; }","duration":"189.746343ms","start":"2026-07-07T16:34:58.339138Z","end":"2026-07-07T16:34:58.528885Z","steps":["trace[131459191] 'process raft request' (duration: 189.596169ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:34:58.76836Z","caller":"traceutil/trace.go:171","msg":"trace[1727619720] transaction","detail":"{read_only:false; response_revision:14757; number_of_response:1; }","duration":"226.823273ms","start":"2026-07-07T16:34:58.541501Z","end":"2026-07-07T16:34:58.768324Z","steps":["trace[1727619720] 'process raft request' (duration: 192.51934ms)","trace[1727619720] 'compare' (duration: 34.1719ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:36:37.482833Z","caller":"traceutil/trace.go:171","msg":"trace[10090747] linearizableReadLoop","detail":"{readStateIndex:16387; appliedIndex:16386; }","duration":"176.012784ms","start":"2026-07-07T16:36:37.306796Z","end":"2026-07-07T16:36:37.482809Z","steps":["trace[10090747] 'read index received' (duration: 175.912882ms)","trace[10090747] 'applied index is now lower than readState.Index' (duration: 98.752µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:36:37.482964Z","caller":"traceutil/trace.go:171","msg":"trace[2105055698] transaction","detail":"{read_only:false; response_revision:15837; number_of_response:1; }","duration":"211.027137ms","start":"2026-07-07T16:36:37.271903Z","end":"2026-07-07T16:36:37.482931Z","steps":["trace[2105055698] 'process raft request' (duration: 210.753701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:37.483346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.431404ms","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-07T16:36:37.483417Z","caller":"traceutil/trace.go:171","msg":"trace[1162153292] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15837; }","duration":"176.603838ms","start":"2026-07-07T16:36:37.30678Z","end":"2026-07-07T16:36:37.483384Z","steps":["trace[1162153292] 'agreement among raft nodes before linearized reading' (duration: 176.233929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.211402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.805578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:36:38.211728Z","caller":"traceutil/trace.go:171","msg":"trace[680913718] transaction","detail":"{read_only:false; response_revision:15840; number_of_response:1; }","duration":"617.546608ms","start":"2026-07-07T16:36:37.594141Z","end":"2026-07-07T16:36:38.211688Z","steps":["trace[680913718] 'process raft request' (duration: 617.500417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.211805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.594117Z","time spent":"617.648971ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:38.212042Z","caller":"traceutil/trace.go:171","msg":"trace[1729708441] transaction","detail":"{read_only:false; response_revision:15839; number_of_response:1; }","duration":"885.925721ms","start":"2026-07-07T16:36:37.326102Z","end":"2026-07-07T16:36:38.212028Z","steps":["trace[1729708441] 'process raft request' (duration: 885.45719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.212107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.326074Z","time spent":"886.002494ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:38.212237Z","caller":"traceutil/trace.go:171","msg":"trace[1876571604] linearizableReadLoop","detail":"{readStateIndex:16389; appliedIndex:16387; }","duration":"729.318916ms","start":"2026-07-07T16:36:37.482909Z","end":"2026-07-07T16:36:38.212228Z","steps":["trace[1876571604] 'read index received' (duration: 105.621436ms)","trace[1876571604] 'applied index is now lower than readState.Index' (duration: 623.69635ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:38.212449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.555298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-07T16:36:38.212478Z","caller":"traceutil/trace.go:171","msg":"trace[1032148672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15840; }","duration":"773.62559ms","start":"2026-07-07T16:36:37.438843Z","end":"2026-07-07T16:36:38.212469Z","steps":["trace[1032148672] 'agreement among raft nodes before linearized reading' (duration: 773.494047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.212507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.438822Z","time spent":"773.679541ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-07T16:36:38.212712Z","caller":"traceutil/trace.go:171","msg":"trace[1098568281] transaction","detail":"{read_only:false; response_revision:15838; number_of_response:1; }","duration":"901.132308ms","start":"2026-07-07T16:36:37.311519Z","end":"2026-07-07T16:36:38.212651Z","steps":["trace[1098568281] 'process raft request' (duration: 277.000106ms)","trace[1098568281] 'compare' (duration: 622.553942ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:38.213141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.311497Z","time spent":"901.562638ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:38.23313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.613123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:38.233127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.731562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:38.233144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.439505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T16:36:38.233201Z","caller":"traceutil/trace.go:171","msg":"trace[993786662] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15841; }","duration":"733.707945ms","start":"2026-07-07T16:36:37.499475Z","end":"2026-07-07T16:36:38.233183Z","steps":["trace[993786662] 'agreement among raft nodes before linearized reading' (duration: 733.600012ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:38.233216Z","caller":"traceutil/trace.go:171","msg":"trace[184305740] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15841; }","duration":"732.842704ms","start":"2026-07-07T16:36:37.500353Z","end":"2026-07-07T16:36:38.233196Z","steps":["trace[184305740] 'agreement among raft nodes before linearized reading' (duration: 732.716571ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:38.233235Z","caller":"traceutil/trace.go:171","msg":"trace[1277003347] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:15841; }","duration":"281.658881ms","start":"2026-07-07T16:36:37.951558Z","end":"2026-07-07T16:36:38.233217Z","steps":["trace[1277003347] 'agreement among raft nodes before linearized reading' (duration: 281.415085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.233449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.263618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:38.233464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.460004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:38.233476Z","caller":"traceutil/trace.go:171","msg":"trace[242346753] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15841; }","duration":"364.32166ms","start":"2026-07-07T16:36:37.869146Z","end":"2026-07-07T16:36:38.233467Z","steps":["trace[242346753] 'agreement among raft nodes before linearized reading' (duration: 364.286779ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:38.233488Z","caller":"traceutil/trace.go:171","msg":"trace[935633139] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15841; }","duration":"364.518605ms","start":"2026-07-07T16:36:37.868959Z","end":"2026-07-07T16:36:38.233477Z","steps":["trace[935633139] 'agreement among raft nodes before linearized reading' (duration: 364.482794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.233511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.868935Z","time spent":"364.569226ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.86913Z","time spent":"364.370622ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.973629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:38.233239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.051824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-07-07T16:36:38.23365Z","caller":"traceutil/trace.go:171","msg":"trace[67718258] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15841; }","duration":"734.518775ms","start":"2026-07-07T16:36:37.499126Z","end":"2026-07-07T16:36:38.233645Z","steps":["trace[67718258] 'agreement among raft nodes before linearized reading' (duration: 734.050724ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:38.233647Z","caller":"traceutil/trace.go:171","msg":"trace[1972834848] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15841; }","duration":"235.052441ms","start":"2026-07-07T16:36:37.998585Z","end":"2026-07-07T16:36:38.233637Z","steps":["trace[1972834848] 'agreement among raft nodes before linearized reading' (duration: 234.99604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.233669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.499104Z","time spent":"734.558346ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":704,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.499465Z","time spent":"733.768087ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.06861ms","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-07T16:36:38.23376Z","caller":"traceutil/trace.go:171","msg":"trace[1657593515] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15841; }","duration":"733.632924ms","start":"2026-07-07T16:36:37.500123Z","end":"2026-07-07T16:36:38.233756Z","steps":["trace[1657593515] 'agreement among raft nodes before linearized reading' (duration: 732.973148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.233775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.500108Z","time spent":"733.663264ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.096266ms","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":"warn","ts":"2026-07-07T16:36:38.233252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.500345Z","time spent":"732.899895ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":29,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.27473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:38.233922Z","caller":"traceutil/trace.go:171","msg":"trace[608206449] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15841; }","duration":"734.909534ms","start":"2026-07-07T16:36:37.498996Z","end":"2026-07-07T16:36:38.233906Z","steps":["trace[608206449] 'agreement among raft nodes before linearized reading' (duration: 734.273899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.233972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.49898Z","time spent":"734.978867ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.555008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-psp95\" ","response":"range_response_count:1 size:22089"} {"level":"warn","ts":"2026-07-07T16:36:38.233996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.225874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-07T16:36:38.23397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.774374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:38.234095Z","caller":"traceutil/trace.go:171","msg":"trace[523551727] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:15841; }","duration":"117.387788ms","start":"2026-07-07T16:36:38.116686Z","end":"2026-07-07T16:36:38.234074Z","steps":["trace[523551727] 'agreement among raft nodes before linearized reading' (duration: 116.371353ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:38.2341Z","caller":"traceutil/trace.go:171","msg":"trace[1690355040] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-psp95; range_end:; response_count:1; response_revision:15841; }","duration":"143.323407ms","start":"2026-07-07T16:36:38.090759Z","end":"2026-07-07T16:36:38.234082Z","steps":["trace[1690355040] 'agreement among raft nodes before linearized reading' (duration: 142.526957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.2334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.937944ms","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-07T16:36:38.234135Z","caller":"traceutil/trace.go:171","msg":"trace[278009970] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15841; }","duration":"235.009259ms","start":"2026-07-07T16:36:37.999102Z","end":"2026-07-07T16:36:38.234111Z","steps":["trace[278009970] 'agreement among raft nodes before linearized reading' (duration: 234.6186ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:38.234237Z","caller":"traceutil/trace.go:171","msg":"trace[1939608093] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15841; }","duration":"230.779154ms","start":"2026-07-07T16:36:38.003442Z","end":"2026-07-07T16:36:38.234222Z","steps":["trace[1939608093] 'agreement among raft nodes before linearized reading' (duration: 229.832821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.23444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.629476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ft9nb\" ","response":"range_response_count:1 size:20631"} {"level":"info","ts":"2026-07-07T16:36:38.234475Z","caller":"traceutil/trace.go:171","msg":"trace[1863064858] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-ft9nb; range_end:; response_count:1; response_revision:15841; }","duration":"723.704978ms","start":"2026-07-07T16:36:37.510762Z","end":"2026-07-07T16:36:38.234467Z","steps":["trace[1863064858] 'agreement among raft nodes before linearized reading' (duration: 722.256373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.234497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.510744Z","time spent":"723.746339ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20655,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ft9nb\" "} {"level":"warn","ts":"2026-07-07T16:36:38.233425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.06807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-psp95\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-07-07T16:36:38.234549Z","caller":"traceutil/trace.go:171","msg":"trace[1574996824] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-psp95; range_end:; response_count:1; response_revision:15841; }","duration":"165.180457ms","start":"2026-07-07T16:36:38.069351Z","end":"2026-07-07T16:36:38.234532Z","steps":["trace[1574996824] 'agreement among raft nodes before linearized reading' (duration: 164.034969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.234108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.515005ms","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-07T16:36:38.234632Z","caller":"traceutil/trace.go:171","msg":"trace[1302936091] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15841; }","duration":"365.440208ms","start":"2026-07-07T16:36:37.869174Z","end":"2026-07-07T16:36:38.234614Z","steps":["trace[1302936091] 'agreement among raft nodes before linearized reading' (duration: 364.2873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.234715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.869149Z","time spent":"365.55207ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-07T16:36:38.234814Z","caller":"traceutil/trace.go:171","msg":"trace[1589762206] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15841; }","duration":"700.173288ms","start":"2026-07-07T16:36:37.533619Z","end":"2026-07-07T16:36:38.233792Z","steps":["trace[1589762206] 'agreement among raft nodes before linearized reading' (duration: 700.115696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.23486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:37.533598Z","time spent":"701.245684ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-07T16:36:38.869025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.917644ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:38.869148Z","caller":"traceutil/trace.go:171","msg":"trace[950972107] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15841; }","duration":"371.082668ms","start":"2026-07-07T16:36:38.498045Z","end":"2026-07-07T16:36:38.869128Z","steps":["trace[950972107] 'range keys from in-memory index tree' (duration: 370.889263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.869829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.068016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:36:38.869884Z","caller":"traceutil/trace.go:171","msg":"trace[662271021] linearizableReadLoop","detail":"{readStateIndex:16392; appliedIndex:16391; }","duration":"636.942077ms","start":"2026-07-07T16:36:38.232933Z","end":"2026-07-07T16:36:38.869875Z","steps":["trace[662271021] 'read index received' (duration: 310.642526ms)","trace[662271021] 'applied index is now lower than readState.Index' (duration: 326.298791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:38.870016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.563263ms","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-07T16:36:38.870043Z","caller":"traceutil/trace.go:171","msg":"trace[1254479579] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15842; }","duration":"652.637505ms","start":"2026-07-07T16:36:38.217399Z","end":"2026-07-07T16:36:38.870037Z","steps":["trace[1254479579] 'agreement among raft nodes before linearized reading' (duration: 652.529042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.870034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.403309ms","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-07T16:36:38.870121Z","caller":"traceutil/trace.go:171","msg":"trace[774381753] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15842; }","duration":"652.540102ms","start":"2026-07-07T16:36:38.217559Z","end":"2026-07-07T16:36:38.870099Z","steps":["trace[774381753] 'agreement among raft nodes before linearized reading' (duration: 652.371178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.870093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.063651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:38.870161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.217539Z","time spent":"652.614135ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-07-07T16:36:38.870229Z","caller":"traceutil/trace.go:171","msg":"trace[1927691775] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15842; }","duration":"652.237545ms","start":"2026-07-07T16:36:38.217967Z","end":"2026-07-07T16:36:38.870205Z","steps":["trace[1927691775] 'agreement among raft nodes before linearized reading' (duration: 651.935508ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:38.87025Z","caller":"traceutil/trace.go:171","msg":"trace[887173781] transaction","detail":"{read_only:false; response_revision:15842; number_of_response:1; }","duration":"653.471625ms","start":"2026-07-07T16:36:38.216767Z","end":"2026-07-07T16:36:38.870238Z","steps":["trace[887173781] 'process raft request' (duration: 326.927407ms)","trace[887173781] 'compare' (duration: 325.626395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:38.870293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.217957Z","time spent":"652.324897ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-07-07T16:36:38.870304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.21674Z","time spent":"653.540127ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:38.870069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.217387Z","time spent":"652.676186ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:38.870471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.659525ms","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-07T16:36:38.870528Z","caller":"traceutil/trace.go:171","msg":"trace[532834576] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15842; }","duration":"652.763528ms","start":"2026-07-07T16:36:38.217754Z","end":"2026-07-07T16:36:38.870518Z","steps":["trace[532834576] 'agreement among raft nodes before linearized reading' (duration: 652.596024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:38.870581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.217727Z","time spent":"652.8388ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-07T16:36:39.77037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.139272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:36:39.770464Z","caller":"traceutil/trace.go:171","msg":"trace[878513211] transaction","detail":"{read_only:false; response_revision:15843; number_of_response:1; }","duration":"1.145808294s","start":"2026-07-07T16:36:38.62464Z","end":"2026-07-07T16:36:39.770448Z","steps":["trace[878513211] 'process raft request' (duration: 677.521758ms)","trace[878513211] 'compare' (duration: 467.895766ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:39.77051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.624614Z","time spent":"1.145873996s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:39.957314Z","caller":"traceutil/trace.go:171","msg":"trace[1547701207] linearizableReadLoop","detail":"{readStateIndex:16394; appliedIndex:16392; }","duration":"1.087381212s","start":"2026-07-07T16:36:38.869904Z","end":"2026-07-07T16:36:39.957285Z","steps":["trace[1547701207] 'read index received' (duration: 432.273006ms)","trace[1547701207] 'applied index is now lower than readState.Index' (duration: 655.106516ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:39.957594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658717071s","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-07T16:36:39.957611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610639355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cdjml\" ","response":"range_response_count:1 size:9787"} {"level":"info","ts":"2026-07-07T16:36:39.957685Z","caller":"traceutil/trace.go:171","msg":"trace[381896932] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cdjml; range_end:; response_count:1; response_revision:15844; }","duration":"1.610796308s","start":"2026-07-07T16:36:38.34687Z","end":"2026-07-07T16:36:39.957666Z","steps":["trace[381896932] 'agreement among raft nodes before linearized reading' (duration: 1.610551442s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.957723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681133645s","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-07T16:36:39.957752Z","caller":"traceutil/trace.go:171","msg":"trace[1088132026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15844; }","duration":"1.681195996s","start":"2026-07-07T16:36:38.276548Z","end":"2026-07-07T16:36:39.957744Z","steps":["trace[1088132026] 'agreement among raft nodes before linearized reading' (duration: 1.681110654s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.957794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.276528Z","time spent":"1.681258807s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T16:36:39.957838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.494663353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:39.957873Z","caller":"traceutil/trace.go:171","msg":"trace[1278500027] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15844; }","duration":"1.494736875s","start":"2026-07-07T16:36:38.463127Z","end":"2026-07-07T16:36:39.957864Z","steps":["trace[1278500027] 'agreement among raft nodes before linearized reading' (duration: 1.494647842s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.957903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.463093Z","time spent":"1.494804807s","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-07T16:36:39.957868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332235895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T16:36:39.957978Z","caller":"traceutil/trace.go:171","msg":"trace[228540753] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:15844; }","duration":"1.332383149s","start":"2026-07-07T16:36:38.625558Z","end":"2026-07-07T16:36:39.957941Z","steps":["trace[228540753] 'agreement among raft nodes before linearized reading' (duration: 1.332202444s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.95808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.625545Z","time spent":"1.332522462s","remote":"127.0.0.1:43254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-07T16:36:39.958208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50951397s","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-07T16:36:39.958245Z","caller":"traceutil/trace.go:171","msg":"trace[1242000778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15844; }","duration":"1.509594412s","start":"2026-07-07T16:36:38.448642Z","end":"2026-07-07T16:36:39.958237Z","steps":["trace[1242000778] 'agreement among raft nodes before linearized reading' (duration: 1.50950414s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.958272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.448621Z","time spent":"1.509645243s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T16:36:39.958337Z","caller":"traceutil/trace.go:171","msg":"trace[2140484630] transaction","detail":"{read_only:false; response_revision:15844; number_of_response:1; }","duration":"1.322215697s","start":"2026-07-07T16:36:38.636101Z","end":"2026-07-07T16:36:39.958317Z","steps":["trace[2140484630] 'process raft request' (duration: 1.269501016s)","trace[2140484630] 'compare' (duration: 51.431039ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:39.95846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.636073Z","time spent":"1.32230982s","remote":"127.0.0.1:42930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18714,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:39.970564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279816711s","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":"info","ts":"2026-07-07T16:36:39.970644Z","caller":"traceutil/trace.go:171","msg":"trace[154358560] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:15844; }","duration":"1.279935185s","start":"2026-07-07T16:36:38.690692Z","end":"2026-07-07T16:36:39.970627Z","steps":["trace[154358560] 'agreement among raft nodes before linearized reading' (duration: 1.267925609s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.970702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.690669Z","time spent":"1.280014896s","remote":"127.0.0.1:43516","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-07T16:36:39.971453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.729319663s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:39.9715Z","caller":"traceutil/trace.go:171","msg":"trace[2115651164] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15844; }","duration":"1.729362004s","start":"2026-07-07T16:36:38.24212Z","end":"2026-07-07T16:36:39.971482Z","steps":["trace[2115651164] 'agreement among raft nodes before linearized reading' (duration: 1.715864422s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.971531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.242108Z","time spent":"1.729412925s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:39.974254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732181554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:39.974306Z","caller":"traceutil/trace.go:171","msg":"trace[1055881339] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15844; }","duration":"1.732249456s","start":"2026-07-07T16:36:38.242046Z","end":"2026-07-07T16:36:39.974295Z","steps":["trace[1055881339] 'agreement among raft nodes before linearized reading' (duration: 1.731357604s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.974333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.242037Z","time spent":"1.732286826s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:39.975498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.740728995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:39.975587Z","caller":"traceutil/trace.go:171","msg":"trace[192924663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15844; }","duration":"1.740845658s","start":"2026-07-07T16:36:38.234712Z","end":"2026-07-07T16:36:39.975558Z","steps":["trace[192924663] 'agreement among raft nodes before linearized reading' (duration: 1.740702355s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.975625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.234699Z","time spent":"1.74091865s","remote":"127.0.0.1:42796","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-07T16:36:39.975746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.627529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-07T16:36:39.957751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.346851Z","time spent":"1.610891111s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9811,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-cdjml\" "} {"level":"info","ts":"2026-07-07T16:36:39.95764Z","caller":"traceutil/trace.go:171","msg":"trace[967523717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15844; }","duration":"1.658813714s","start":"2026-07-07T16:36:38.298815Z","end":"2026-07-07T16:36:39.957629Z","steps":["trace[967523717] 'agreement among raft nodes before linearized reading' (duration: 1.658635399s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.975819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.298795Z","time spent":"1.677012554s","remote":"127.0.0.1:43036","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-07T16:36:39.976158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.565642ms","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-07T16:36:39.976196Z","caller":"traceutil/trace.go:171","msg":"trace[1091704133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15844; }","duration":"487.627384ms","start":"2026-07-07T16:36:39.488549Z","end":"2026-07-07T16:36:39.976177Z","steps":["trace[1091704133] 'agreement among raft nodes before linearized reading' (duration: 487.428349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.976217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.488532Z","time spent":"487.679724ms","remote":"127.0.0.1:43036","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":"warn","ts":"2026-07-07T16:36:39.976419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.778556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:39.976456Z","caller":"traceutil/trace.go:171","msg":"trace[1107868312] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:15844; }","duration":"489.807457ms","start":"2026-07-07T16:36:39.486631Z","end":"2026-07-07T16:36:39.976439Z","steps":["trace[1107868312] 'agreement among raft nodes before linearized reading' (duration: 489.751706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.976476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.486581Z","time spent":"489.888829ms","remote":"127.0.0.1:32810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-07-07T16:36:39.976905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092718055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:39.976988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.12815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b4lv9\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-07T16:36:39.977026Z","caller":"traceutil/trace.go:171","msg":"trace[1359483505] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b4lv9; range_end:; response_count:1; response_revision:15844; }","duration":"891.181782ms","start":"2026-07-07T16:36:39.085826Z","end":"2026-07-07T16:36:39.977007Z","steps":["trace[1359483505] 'agreement among raft nodes before linearized reading' (duration: 891.080069ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:39.977028Z","caller":"traceutil/trace.go:171","msg":"trace[2007404647] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15844; }","duration":"1.092844028s","start":"2026-07-07T16:36:38.884156Z","end":"2026-07-07T16:36:39.977Z","steps":["trace[2007404647] 'agreement among raft nodes before linearized reading' (duration: 1.092461248s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.977051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.085811Z","time spent":"891.236433ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-b4lv9\" "} {"level":"warn","ts":"2026-07-07T16:36:39.977064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.884149Z","time spent":"1.092907529s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-07-07T16:36:39.977357Z","caller":"traceutil/trace.go:171","msg":"trace[2012068126] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15844; }","duration":"487.92107ms","start":"2026-07-07T16:36:39.48909Z","end":"2026-07-07T16:36:39.977011Z","steps":["trace[2012068126] 'agreement among raft nodes before linearized reading' (duration: 486.585827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.977483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.489082Z","time spent":"488.358002ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-07T16:36:39.977603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093190247s","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-07T16:36:39.977701Z","caller":"traceutil/trace.go:171","msg":"trace[2105179067] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15844; }","duration":"1.09329135s","start":"2026-07-07T16:36:38.88437Z","end":"2026-07-07T16:36:39.977661Z","steps":["trace[2105179067] 'agreement among raft nodes before linearized reading' (duration: 1.092712125s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.977763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.884362Z","time spent":"1.093385842s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:39.977839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093836932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-07T16:36:39.977945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.682818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T16:36:39.977977Z","caller":"traceutil/trace.go:171","msg":"trace[781596826] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15844; }","duration":"1.093997836s","start":"2026-07-07T16:36:38.883965Z","end":"2026-07-07T16:36:39.977963Z","steps":["trace[781596826] 'agreement among raft nodes before linearized reading' (duration: 1.093021631s)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:39.978053Z","caller":"traceutil/trace.go:171","msg":"trace[2110505323] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:15844; }","duration":"954.75659ms","start":"2026-07-07T16:36:39.023217Z","end":"2026-07-07T16:36:39.977974Z","steps":["trace[2110505323] 'agreement among raft nodes before linearized reading' (duration: 953.787636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.978066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.883919Z","time spent":"1.094136409s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-07T16:36:39.97808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.023199Z","time spent":"954.873093ms","remote":"127.0.0.1:43032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-07T16:36:39.978161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094223151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:39.978212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.567461ms","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-07T16:36:39.978231Z","caller":"traceutil/trace.go:171","msg":"trace[238317398] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15844; }","duration":"1.094306284s","start":"2026-07-07T16:36:38.883896Z","end":"2026-07-07T16:36:39.978202Z","steps":["trace[238317398] 'agreement among raft nodes before linearized reading' (duration: 1.093098073s)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:39.978245Z","caller":"traceutil/trace.go:171","msg":"trace[289235502] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15844; }","duration":"504.673173ms","start":"2026-07-07T16:36:39.473555Z","end":"2026-07-07T16:36:39.978228Z","steps":["trace[289235502] 'agreement among raft nodes before linearized reading' (duration: 503.519164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.978269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.47354Z","time spent":"504.722905ms","remote":"127.0.0.1:58064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-07T16:36:39.97827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.883882Z","time spent":"1.094380415s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-07-07T16:36:39.978682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.771789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T16:36:39.978725Z","caller":"traceutil/trace.go:171","msg":"trace[277780597] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:15844; }","duration":"526.81117ms","start":"2026-07-07T16:36:39.451898Z","end":"2026-07-07T16:36:39.978709Z","steps":["trace[277780597] 'agreement among raft nodes before linearized reading' (duration: 525.18137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.978766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.451791Z","time spent":"526.948973ms","remote":"127.0.0.1:36228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":107,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-07T16:36:39.983311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742496499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:39.983426Z","caller":"traceutil/trace.go:171","msg":"trace[110956297] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15844; }","duration":"1.742629152s","start":"2026-07-07T16:36:38.240756Z","end":"2026-07-07T16:36:39.983385Z","steps":["trace[110956297] 'agreement among raft nodes before linearized reading' (duration: 1.734637345s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:39.983505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:38.240738Z","time spent":"1.742746634s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-07T16:36:40.32509Z","caller":"traceutil/trace.go:171","msg":"trace[1996968412] linearizableReadLoop","detail":"{readStateIndex:16397; appliedIndex:16394; }","duration":"337.13931ms","start":"2026-07-07T16:36:39.98793Z","end":"2026-07-07T16:36:40.32507Z","steps":["trace[1996968412] 'read index received' (duration: 61.240612ms)","trace[1996968412] 'applied index is now lower than readState.Index' (duration: 275.898058ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:36:40.325127Z","caller":"traceutil/trace.go:171","msg":"trace[647431132] transaction","detail":"{read_only:false; response_revision:15846; number_of_response:1; }","duration":"342.013589ms","start":"2026-07-07T16:36:39.983088Z","end":"2026-07-07T16:36:40.325102Z","steps":["trace[647431132] 'process raft request' (duration: 341.804334ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.325174Z","caller":"traceutil/trace.go:171","msg":"trace[1710482486] transaction","detail":"{read_only:false; response_revision:15848; number_of_response:1; }","duration":"337.175141ms","start":"2026-07-07T16:36:39.98799Z","end":"2026-07-07T16:36:40.325165Z","steps":["trace[1710482486] 'process raft request' (duration: 337.050858ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.325226Z","caller":"traceutil/trace.go:171","msg":"trace[1280001725] transaction","detail":"{read_only:false; response_revision:15847; number_of_response:1; }","duration":"340.117354ms","start":"2026-07-07T16:36:39.985045Z","end":"2026-07-07T16:36:40.325162Z","steps":["trace[1280001725] 'process raft request' (duration: 339.9713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.325281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.983071Z","time spent":"342.158753ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:40.325284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.332645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:40.325351Z","caller":"traceutil/trace.go:171","msg":"trace[1458022971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15848; }","duration":"337.450628ms","start":"2026-07-07T16:36:39.987883Z","end":"2026-07-07T16:36:40.325334Z","steps":["trace[1458022971] 'agreement among raft nodes before linearized reading' (duration: 337.301114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.325388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.987873Z","time spent":"337.509289ms","remote":"127.0.0.1:42784","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-07T16:36:40.325268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.987963Z","time spent":"337.225682ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:40.325473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.984999Z","time spent":"340.335559ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:40.460081Z","caller":"traceutil/trace.go:171","msg":"trace[1321042690] transaction","detail":"{read_only:false; response_revision:15849; number_of_response:1; }","duration":"241.734606ms","start":"2026-07-07T16:36:40.218303Z","end":"2026-07-07T16:36:40.460038Z","steps":["trace[1321042690] 'process raft request' (duration: 171.257587ms)","trace[1321042690] 'compare' (duration: 70.203532ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:36:40.460421Z","caller":"traceutil/trace.go:171","msg":"trace[812561057] transaction","detail":"{read_only:false; response_revision:15850; number_of_response:1; }","duration":"241.334545ms","start":"2026-07-07T16:36:40.21905Z","end":"2026-07-07T16:36:40.460385Z","steps":["trace[812561057] 'process raft request' (duration: 240.934005ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.460572Z","caller":"traceutil/trace.go:171","msg":"trace[522643566] transaction","detail":"{read_only:false; response_revision:15851; number_of_response:1; }","duration":"219.139077ms","start":"2026-07-07T16:36:40.241406Z","end":"2026-07-07T16:36:40.460545Z","steps":["trace[522643566] 'process raft request' (duration: 218.84425ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.460623Z","caller":"traceutil/trace.go:171","msg":"trace[2047894911] linearizableReadLoop","detail":"{readStateIndex:16401; appliedIndex:16398; }","duration":"135.471363ms","start":"2026-07-07T16:36:40.325139Z","end":"2026-07-07T16:36:40.46061Z","steps":["trace[2047894911] 'read index received' (duration: 64.544043ms)","trace[2047894911] 'applied index is now lower than readState.Index' (duration: 70.92607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:40.460796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.749771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-07T16:36:40.460797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.563093ms","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-07T16:36:40.460824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.591103ms","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-07T16:36:40.460839Z","caller":"traceutil/trace.go:171","msg":"trace[167434482] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15852; }","duration":"219.812434ms","start":"2026-07-07T16:36:40.241014Z","end":"2026-07-07T16:36:40.460826Z","steps":["trace[167434482] 'agreement among raft nodes before linearized reading' (duration: 219.680051ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.46086Z","caller":"traceutil/trace.go:171","msg":"trace[184226531] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15852; }","duration":"220.653315ms","start":"2026-07-07T16:36:40.240196Z","end":"2026-07-07T16:36:40.460849Z","steps":["trace[184226531] 'agreement among raft nodes before linearized reading' (duration: 220.494881ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.46086Z","caller":"traceutil/trace.go:171","msg":"trace[882291697] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15852; }","duration":"220.670315ms","start":"2026-07-07T16:36:40.240175Z","end":"2026-07-07T16:36:40.460845Z","steps":["trace[882291697] 'agreement among raft nodes before linearized reading' (duration: 220.481811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.460959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.820739ms","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-07T16:36:40.460991Z","caller":"traceutil/trace.go:171","msg":"trace[1983763654] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15852; }","duration":"220.879501ms","start":"2026-07-07T16:36:40.240104Z","end":"2026-07-07T16:36:40.460983Z","steps":["trace[1983763654] 'agreement among raft nodes before linearized reading' (duration: 220.8344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.46109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.590924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:40.461128Z","caller":"traceutil/trace.go:171","msg":"trace[1142819423] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15852; }","duration":"218.642405ms","start":"2026-07-07T16:36:40.242476Z","end":"2026-07-07T16:36:40.461119Z","steps":["trace[1142819423] 'agreement among raft nodes before linearized reading' (duration: 218.550153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.461189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.161232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-07-07T16:36:40.461246Z","caller":"traceutil/trace.go:171","msg":"trace[769966085] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15852; }","duration":"220.236304ms","start":"2026-07-07T16:36:40.240994Z","end":"2026-07-07T16:36:40.461231Z","steps":["trace[769966085] 'agreement among raft nodes before linearized reading' (duration: 220.10098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.461277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.25008ms","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-07T16:36:40.461312Z","caller":"traceutil/trace.go:171","msg":"trace[1150406996] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15852; }","duration":"219.304901ms","start":"2026-07-07T16:36:40.241998Z","end":"2026-07-07T16:36:40.461302Z","steps":["trace[1150406996] 'agreement among raft nodes before linearized reading' (duration: 219.25542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.461466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.410608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:40.461482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.469139ms","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-07T16:36:40.461528Z","caller":"traceutil/trace.go:171","msg":"trace[2117098445] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15852; }","duration":"220.513471ms","start":"2026-07-07T16:36:40.241Z","end":"2026-07-07T16:36:40.461514Z","steps":["trace[2117098445] 'agreement among raft nodes before linearized reading' (duration: 220.443169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.461869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.587008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-07T16:36:40.461877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.057699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:40.461924Z","caller":"traceutil/trace.go:171","msg":"trace[533836423] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15852; }","duration":"222.10543ms","start":"2026-07-07T16:36:40.23981Z","end":"2026-07-07T16:36:40.461916Z","steps":["trace[533836423] 'agreement among raft nodes before linearized reading' (duration: 222.005348ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.461928Z","caller":"traceutil/trace.go:171","msg":"trace[1059448817] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15852; }","duration":"221.68402ms","start":"2026-07-07T16:36:40.240228Z","end":"2026-07-07T16:36:40.461912Z","steps":["trace[1059448817] 'agreement among raft nodes before linearized reading' (duration: 221.517406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.461925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.893525ms","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-07T16:36:40.461976Z","caller":"traceutil/trace.go:171","msg":"trace[1631491713] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15852; }","duration":"221.961546ms","start":"2026-07-07T16:36:40.240002Z","end":"2026-07-07T16:36:40.461963Z","steps":["trace[1631491713] 'agreement among raft nodes before linearized reading' (duration: 221.879374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.462182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.345692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:40.46222Z","caller":"traceutil/trace.go:171","msg":"trace[1663335061] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15852; }","duration":"223.409312ms","start":"2026-07-07T16:36:40.238802Z","end":"2026-07-07T16:36:40.462211Z","steps":["trace[1663335061] 'agreement among raft nodes before linearized reading' (duration: 223.29867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.462189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.15435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:40.461499Z","caller":"traceutil/trace.go:171","msg":"trace[1265946693] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15852; }","duration":"220.457249ms","start":"2026-07-07T16:36:40.241033Z","end":"2026-07-07T16:36:40.461491Z","steps":["trace[1265946693] 'agreement among raft nodes before linearized reading' (duration: 220.401238ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:40.462356Z","caller":"traceutil/trace.go:171","msg":"trace[1041161958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15852; }","duration":"136.353004ms","start":"2026-07-07T16:36:40.325983Z","end":"2026-07-07T16:36:40.462336Z","steps":["trace[1041161958] 'agreement among raft nodes before linearized reading' (duration: 136.17638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.462478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.241173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:40.462657Z","caller":"traceutil/trace.go:171","msg":"trace[1786997037] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15852; }","duration":"474.330525ms","start":"2026-07-07T16:36:39.988196Z","end":"2026-07-07T16:36:40.462527Z","steps":["trace[1786997037] 'agreement among raft nodes before linearized reading' (duration: 473.158936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.462715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.988186Z","time spent":"474.513489ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:40.463109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.92299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:40.463154Z","caller":"traceutil/trace.go:171","msg":"trace[600830116] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15852; }","duration":"474.984891ms","start":"2026-07-07T16:36:39.98816Z","end":"2026-07-07T16:36:40.463145Z","steps":["trace[600830116] 'agreement among raft nodes before linearized reading' (duration: 474.048358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:40.463184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:39.988154Z","time spent":"475.019882ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:40.464646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.563461ms","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-07T16:36:40.464695Z","caller":"traceutil/trace.go:171","msg":"trace[527431176] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15852; }","duration":"224.618532ms","start":"2026-07-07T16:36:40.240058Z","end":"2026-07-07T16:36:40.464677Z","steps":["trace[527431176] 'agreement among raft nodes before linearized reading' (duration: 221.080335ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:41.033489Z","caller":"traceutil/trace.go:171","msg":"trace[1054707080] linearizableReadLoop","detail":"{readStateIndex:16403; appliedIndex:16402; }","duration":"125.399694ms","start":"2026-07-07T16:36:40.90807Z","end":"2026-07-07T16:36:41.03347Z","steps":["trace[1054707080] 'read index received' (duration: 125.22401ms)","trace[1054707080] 'applied index is now lower than readState.Index' (duration: 175.144µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:41.033691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.59959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-07T16:36:41.033724Z","caller":"traceutil/trace.go:171","msg":"trace[2126685214] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:15853; }","duration":"125.670411ms","start":"2026-07-07T16:36:40.908047Z","end":"2026-07-07T16:36:41.033717Z","steps":["trace[2126685214] 'agreement among raft nodes before linearized reading' (duration: 125.503816ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:41.033926Z","caller":"traceutil/trace.go:171","msg":"trace[2114819709] transaction","detail":"{read_only:false; response_revision:15853; number_of_response:1; }","duration":"155.514428ms","start":"2026-07-07T16:36:40.878404Z","end":"2026-07-07T16:36:41.033919Z","steps":["trace[2114819709] 'process raft request' (duration: 154.949574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:41.284378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.233208ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-07T16:36:41.284468Z","caller":"traceutil/trace.go:171","msg":"trace[1765867453] linearizableReadLoop","detail":"{readStateIndex:16404; appliedIndex:16403; }","duration":"214.488323ms","start":"2026-07-07T16:36:41.069962Z","end":"2026-07-07T16:36:41.28445Z","steps":["trace[1765867453] 'read index received' (duration: 66.120772ms)","trace[1765867453] 'applied index is now lower than readState.Index' (duration: 148.365951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:41.284573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.608516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:41.284608Z","caller":"traceutil/trace.go:171","msg":"trace[317443131] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:15853; }","duration":"214.677687ms","start":"2026-07-07T16:36:41.06992Z","end":"2026-07-07T16:36:41.284597Z","steps":["trace[317443131] 'agreement among raft nodes before linearized reading' (duration: 214.610976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:41.284804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.778545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:41.284895Z","caller":"traceutil/trace.go:171","msg":"trace[1736899550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15853; }","duration":"149.948459ms","start":"2026-07-07T16:36:41.134926Z","end":"2026-07-07T16:36:41.284874Z","steps":["trace[1736899550] 'agreement among raft nodes before linearized reading' (duration: 149.782575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:42.418362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028791338s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:36:42.418559Z","caller":"traceutil/trace.go:171","msg":"trace[1117167049] transaction","detail":"{read_only:false; response_revision:15854; number_of_response:1; }","duration":"1.13267106s","start":"2026-07-07T16:36:41.285847Z","end":"2026-07-07T16:36:42.418518Z","steps":["trace[1117167049] 'process raft request' (duration: 103.461702ms)","trace[1117167049] 'compare' (duration: 1.027067155s)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:42.418669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.285824Z","time spent":"1.132798304s","remote":"127.0.0.1:42804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:42.465818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794900431281626993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-07T16:36:43.003727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.902916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:36:43.003837Z","caller":"traceutil/trace.go:171","msg":"trace[458950100] transaction","detail":"{read_only:false; response_revision:15855; number_of_response:1; }","duration":"1.227802287s","start":"2026-07-07T16:36:41.776014Z","end":"2026-07-07T16:36:43.003816Z","steps":["trace[458950100] 'process raft request' (duration: 759.718756ms)","trace[458950100] 'compare' (duration: 467.701892ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:43.003899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.775985Z","time spent":"1.227883879s","remote":"127.0.0.1:43036","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-07T16:36:43.430293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.474637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T16:36:43.430597Z","caller":"traceutil/trace.go:171","msg":"trace[1149050939] transaction","detail":"{read_only:false; response_revision:15856; number_of_response:1; }","duration":"1.527029412s","start":"2026-07-07T16:36:41.903529Z","end":"2026-07-07T16:36:43.430558Z","steps":["trace[1149050939] 'process raft request' (duration: 1.311160045s)","trace[1149050939] 'compare' (duration: 215.070798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:43.430709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.903495Z","time spent":"1.527171765s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:43.430759Z","caller":"traceutil/trace.go:171","msg":"trace[2094038049] transaction","detail":"{read_only:false; response_revision:15857; number_of_response:1; }","duration":"1.168408252s","start":"2026-07-07T16:36:42.262299Z","end":"2026-07-07T16:36:43.430707Z","steps":["trace[2094038049] 'process raft request' (duration: 1.168120675s)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.430919Z","caller":"traceutil/trace.go:171","msg":"trace[1180449694] transaction","detail":"{read_only:false; response_revision:15858; number_of_response:1; }","duration":"964.262825ms","start":"2026-07-07T16:36:42.466635Z","end":"2026-07-07T16:36:43.430897Z","steps":["trace[1180449694] 'process raft request' (duration: 964.106661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.430953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.262278Z","time spent":"1.168569866s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:43.431031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.466597Z","time spent":"964.386548ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:43.431315Z","caller":"traceutil/trace.go:171","msg":"trace[1396057788] transaction","detail":"{read_only:false; response_revision:15861; number_of_response:1; }","duration":"831.280202ms","start":"2026-07-07T16:36:42.600019Z","end":"2026-07-07T16:36:43.431299Z","steps":["trace[1396057788] 'process raft request' (duration: 831.210151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.431393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.599989Z","time spent":"831.365615ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:43.431528Z","caller":"traceutil/trace.go:171","msg":"trace[1398636642] transaction","detail":"{read_only:false; response_revision:15859; number_of_response:1; }","duration":"963.114996ms","start":"2026-07-07T16:36:42.468378Z","end":"2026-07-07T16:36:43.431493Z","steps":["trace[1398636642] 'process raft request' (duration: 962.44548ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.431596Z","caller":"traceutil/trace.go:171","msg":"trace[1348595828] transaction","detail":"{read_only:false; response_revision:15860; number_of_response:1; }","duration":"962.217785ms","start":"2026-07-07T16:36:42.469357Z","end":"2026-07-07T16:36:43.431575Z","steps":["trace[1348595828] 'process raft request' (duration: 961.810294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.431711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.469344Z","time spent":"962.320557ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:43.431887Z","caller":"traceutil/trace.go:171","msg":"trace[909796678] linearizableReadLoop","detail":"{readStateIndex:16408; appliedIndex:16405; }","duration":"1.466388836s","start":"2026-07-07T16:36:41.965485Z","end":"2026-07-07T16:36:43.431874Z","steps":["trace[909796678] 'read index received' (duration: 570.266133ms)","trace[909796678] 'applied index is now lower than readState.Index' (duration: 896.121183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T16:36:43.431708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.468368Z","time spent":"963.24559ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:43.432253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466767855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:43.432305Z","caller":"traceutil/trace.go:171","msg":"trace[16625111] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15861; }","duration":"1.466875038s","start":"2026-07-07T16:36:41.965417Z","end":"2026-07-07T16:36:43.432292Z","steps":["trace[16625111] 'agreement among raft nodes before linearized reading' (duration: 1.466674104s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.432346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.965393Z","time spent":"1.4669448s","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-07T16:36:43.432526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447378377s","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-07T16:36:43.432633Z","caller":"traceutil/trace.go:171","msg":"trace[1879577900] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"1.4475328s","start":"2026-07-07T16:36:41.985087Z","end":"2026-07-07T16:36:43.43262Z","steps":["trace[1879577900] 'agreement among raft nodes before linearized reading' (duration: 1.447311275s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.432677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.985058Z","time spent":"1.447611303s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-07T16:36:43.432835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.315781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:43.432882Z","caller":"traceutil/trace.go:171","msg":"trace[36561607] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15861; }","duration":"963.377373ms","start":"2026-07-07T16:36:42.469492Z","end":"2026-07-07T16:36:43.432869Z","steps":["trace[36561607] 'agreement among raft nodes before linearized reading' (duration: 963.26208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.432912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.122781ms","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-07T16:36:43.432918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.469485Z","time spent":"963.425875ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-07-07T16:36:43.432939Z","caller":"traceutil/trace.go:171","msg":"trace[302716527] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15861; }","duration":"964.197173ms","start":"2026-07-07T16:36:42.468733Z","end":"2026-07-07T16:36:43.43293Z","steps":["trace[302716527] 'agreement among raft nodes before linearized reading' (duration: 964.120421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.432961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.468706Z","time spent":"964.250635ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.936062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-07T16:36:43.433138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.91887ms","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-07T16:36:43.433147Z","caller":"traceutil/trace.go:171","msg":"trace[382768192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15861; }","duration":"394.013104ms","start":"2026-07-07T16:36:43.039123Z","end":"2026-07-07T16:36:43.433137Z","steps":["trace[382768192] 'agreement among raft nodes before linearized reading' (duration: 393.894151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.43319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.039104Z","time spent":"394.075985ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01230955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-07T16:36:43.433274Z","caller":"traceutil/trace.go:171","msg":"trace[1100146039] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:15861; }","duration":"1.012386101s","start":"2026-07-07T16:36:42.420883Z","end":"2026-07-07T16:36:43.433269Z","steps":["trace[1100146039] 'agreement among raft nodes before linearized reading' (duration: 1.01233461s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.433292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.420855Z","time spent":"1.012433693s","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096323964s","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-07T16:36:43.433415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.783587ms","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-07T16:36:43.433431Z","caller":"traceutil/trace.go:171","msg":"trace[202138413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15861; }","duration":"1.096376955s","start":"2026-07-07T16:36:42.337047Z","end":"2026-07-07T16:36:43.433424Z","steps":["trace[202138413] 'agreement among raft nodes before linearized reading' (duration: 1.096321274s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.433452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.337032Z","time spent":"1.096416356s","remote":"127.0.0.1:43036","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":"info","ts":"2026-07-07T16:36:43.433454Z","caller":"traceutil/trace.go:171","msg":"trace[1195852781] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15861; }","duration":"551.847247ms","start":"2026-07-07T16:36:42.881595Z","end":"2026-07-07T16:36:43.433442Z","steps":["trace[1195852781] 'agreement among raft nodes before linearized reading' (duration: 551.777496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.433491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.881589Z","time spent":"551.895639ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104631429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-07T16:36:43.433601Z","caller":"traceutil/trace.go:171","msg":"trace[1323013757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15861; }","duration":"1.10471363s","start":"2026-07-07T16:36:42.328879Z","end":"2026-07-07T16:36:43.433593Z","steps":["trace[1323013757] 'agreement among raft nodes before linearized reading' (duration: 1.104653849s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.433661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.715169ms","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-07T16:36:43.433699Z","caller":"traceutil/trace.go:171","msg":"trace[1698993584] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15861; }","duration":"552.78937ms","start":"2026-07-07T16:36:42.880899Z","end":"2026-07-07T16:36:43.433688Z","steps":["trace[1698993584] 'agreement among raft nodes before linearized reading' (duration: 552.731429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.433744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.880875Z","time spent":"552.859593ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.534279ms","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-07T16:36:43.433895Z","caller":"traceutil/trace.go:171","msg":"trace[1566904707] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15861; }","duration":"553.561379ms","start":"2026-07-07T16:36:42.880324Z","end":"2026-07-07T16:36:43.433885Z","steps":["trace[1566904707] 'agreement among raft nodes before linearized reading' (duration: 553.519848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.433917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.880261Z","time spent":"553.651342ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-07-07T16:36:43.434163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.228184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:43.434203Z","caller":"traceutil/trace.go:171","msg":"trace[836951889] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15861; }","duration":"964.299166ms","start":"2026-07-07T16:36:42.469892Z","end":"2026-07-07T16:36:43.434191Z","steps":["trace[836951889] 'agreement among raft nodes before linearized reading' (duration: 964.205733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.503337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:43.434232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.469875Z","time spent":"964.351717ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-07-07T16:36:43.434257Z","caller":"traceutil/trace.go:171","msg":"trace[320743222] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15861; }","duration":"961.600079ms","start":"2026-07-07T16:36:42.472644Z","end":"2026-07-07T16:36:43.434244Z","steps":["trace[320743222] 'agreement among raft nodes before linearized reading' (duration: 961.512367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.472608Z","time spent":"961.671851ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":29,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.328858Z","time spent":"1.104760382s","remote":"127.0.0.1:43036","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-07T16:36:43.434425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.884213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-b4lv9\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-07-07T16:36:43.434437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.972968ms","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-07T16:36:43.434472Z","caller":"traceutil/trace.go:171","msg":"trace[1572720249] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15861; }","duration":"962.008469ms","start":"2026-07-07T16:36:42.472455Z","end":"2026-07-07T16:36:43.434464Z","steps":["trace[1572720249] 'agreement among raft nodes before linearized reading' (duration: 961.956428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449205082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:43.434499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449346976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:43.434509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.360094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cdjml\" ","response":"range_response_count:1 size:9787"} {"level":"info","ts":"2026-07-07T16:36:43.434527Z","caller":"traceutil/trace.go:171","msg":"trace[725420837] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cdjml; range_end:; response_count:1; response_revision:15861; }","duration":"957.429017ms","start":"2026-07-07T16:36:42.477093Z","end":"2026-07-07T16:36:43.434522Z","steps":["trace[725420837] 'agreement among raft nodes before linearized reading' (duration: 957.367315ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.434532Z","caller":"traceutil/trace.go:171","msg":"trace[98510735] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15861; }","duration":"1.449304184s","start":"2026-07-07T16:36:41.985208Z","end":"2026-07-07T16:36:43.434512Z","steps":["trace[98510735] 'agreement among raft nodes before linearized reading' (duration: 1.449206702s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.122783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"warn","ts":"2026-07-07T16:36:43.434581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.985184Z","time spent":"1.449389267s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-07-07T16:36:43.434624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.961583ms","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-07T16:36:43.434651Z","caller":"traceutil/trace.go:171","msg":"trace[2141773094] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"961.001894ms","start":"2026-07-07T16:36:42.473643Z","end":"2026-07-07T16:36:43.434645Z","steps":["trace[2141773094] 'agreement among raft nodes before linearized reading' (duration: 960.921292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.163343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-07T16:36:43.434689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.473634Z","time spent":"961.050856ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-07T16:36:43.434705Z","caller":"traceutil/trace.go:171","msg":"trace[1871354086] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"964.210714ms","start":"2026-07-07T16:36:42.470487Z","end":"2026-07-07T16:36:43.434698Z","steps":["trace[1871354086] 'agreement among raft nodes before linearized reading' (duration: 964.134292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.470476Z","time spent":"964.250635ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-07T16:36:43.434831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.787488ms","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-07T16:36:43.434859Z","caller":"traceutil/trace.go:171","msg":"trace[1967020564] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15861; }","duration":"964.85631ms","start":"2026-07-07T16:36:42.469996Z","end":"2026-07-07T16:36:43.434852Z","steps":["trace[1967020564] 'agreement among raft nodes before linearized reading' (duration: 964.810029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.469987Z","time spent":"964.89274ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-07-07T16:36:43.434448Z","caller":"traceutil/trace.go:171","msg":"trace[812789121] range","detail":"{range_begin:/registry/pods/openstack/keepalived-b4lv9; range_end:; response_count:1; response_revision:15861; }","duration":"954.929505ms","start":"2026-07-07T16:36:42.479512Z","end":"2026-07-07T16:36:43.434441Z","steps":["trace[812789121] 'agreement among raft nodes before linearized reading' (duration: 954.845783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.434962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.479501Z","time spent":"955.453777ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-b4lv9\" "} {"level":"warn","ts":"2026-07-07T16:36:43.435002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381228754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-07T16:36:43.435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445585502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-psp95\" ","response":"range_response_count:1 size:22089"} {"level":"info","ts":"2026-07-07T16:36:43.435033Z","caller":"traceutil/trace.go:171","msg":"trace[995667530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15861; }","duration":"1.381313896s","start":"2026-07-07T16:36:42.053712Z","end":"2026-07-07T16:36:43.435026Z","steps":["trace[995667530] 'agreement among raft nodes before linearized reading' (duration: 1.381241044s)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.435048Z","caller":"traceutil/trace.go:171","msg":"trace[1652190268] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-psp95; range_end:; response_count:1; response_revision:15861; }","duration":"1.445659623s","start":"2026-07-07T16:36:41.989376Z","end":"2026-07-07T16:36:43.435035Z","steps":["trace[1652190268] 'agreement among raft nodes before linearized reading' (duration: 1.445455878s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.05368Z","time spent":"1.381373988s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-07T16:36:43.435075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.989364Z","time spent":"1.445705545s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22113,"request content":"key:\"/registry/pods/openstack/nova-compute-default-psp95\" "} {"level":"warn","ts":"2026-07-07T16:36:43.435067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106114616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-07T16:36:43.435117Z","caller":"traceutil/trace.go:171","msg":"trace[1887834125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15861; }","duration":"1.106225008s","start":"2026-07-07T16:36:42.328879Z","end":"2026-07-07T16:36:43.435104Z","steps":["trace[1887834125] 'agreement among raft nodes before linearized reading' (duration: 1.106089535s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.43515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.328848Z","time spent":"1.10629489s","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-07T16:36:43.435215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436347854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ft9nb\" ","response":"range_response_count:1 size:20631"} {"level":"warn","ts":"2026-07-07T16:36:43.434552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.477066Z","time spent":"957.481717ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9811,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-cdjml\" "} {"level":"info","ts":"2026-07-07T16:36:43.435241Z","caller":"traceutil/trace.go:171","msg":"trace[531027401] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-ft9nb; range_end:; response_count:1; response_revision:15861; }","duration":"1.436394295s","start":"2026-07-07T16:36:41.998839Z","end":"2026-07-07T16:36:43.435234Z","steps":["trace[531027401] 'agreement among raft nodes before linearized reading' (duration: 1.436322183s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.998824Z","time spent":"1.436434377s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20655,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-ft9nb\" "} {"level":"info","ts":"2026-07-07T16:36:43.434528Z","caller":"traceutil/trace.go:171","msg":"trace[1412897141] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15861; }","duration":"1.449412458s","start":"2026-07-07T16:36:41.985108Z","end":"2026-07-07T16:36:43.43452Z","steps":["trace[1412897141] 'agreement among raft nodes before linearized reading' (duration: 1.449379597s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.985086Z","time spent":"1.450256038s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-07-07T16:36:43.434472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449213552s","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-07T16:36:43.434606Z","caller":"traceutil/trace.go:171","msg":"trace[966828169] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15861; }","duration":"962.173294ms","start":"2026-07-07T16:36:42.472423Z","end":"2026-07-07T16:36:43.434596Z","steps":["trace[966828169] 'agreement among raft nodes before linearized reading' (duration: 962.095202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295871648s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:43.435422Z","caller":"traceutil/trace.go:171","msg":"trace[874987467] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15861; }","duration":"1.450185256s","start":"2026-07-07T16:36:41.985226Z","end":"2026-07-07T16:36:43.435411Z","steps":["trace[874987467] 'agreement among raft nodes before linearized reading' (duration: 1.449216753s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.472412Z","time spent":"963.013504ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":704,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-07-07T16:36:43.435458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.985211Z","time spent":"1.450233157s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:43.435362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440054905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-psp95\" ","response":"range_response_count:1 size:22089"} {"level":"warn","ts":"2026-07-07T16:36:43.434498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.472393Z","time spent":"962.100142ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-07-07T16:36:43.435534Z","caller":"traceutil/trace.go:171","msg":"trace[86069430] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-psp95; range_end:; response_count:1; response_revision:15861; }","duration":"1.44025108s","start":"2026-07-07T16:36:41.995273Z","end":"2026-07-07T16:36:43.435524Z","steps":["trace[86069430] 'agreement among raft nodes before linearized reading' (duration: 1.440043895s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.995257Z","time spent":"1.440295581s","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22113,"request content":"key:\"/registry/pods/openstack/nova-compute-default-psp95\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.939347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-07T16:36:43.435612Z","caller":"traceutil/trace.go:171","msg":"trace[1158217719] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15861; }","duration":"968.385947ms","start":"2026-07-07T16:36:42.467216Z","end":"2026-07-07T16:36:43.435602Z","steps":["trace[1158217719] 'agreement among raft nodes before linearized reading' (duration: 965.957847ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.435456Z","caller":"traceutil/trace.go:171","msg":"trace[1424135207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15861; }","duration":"1.296187586s","start":"2026-07-07T16:36:42.139255Z","end":"2026-07-07T16:36:43.435443Z","steps":["trace[1424135207] 'agreement among raft nodes before linearized reading' (duration: 1.295884349s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.798983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T16:36:43.435643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.467204Z","time spent":"968.430778ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":691,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-07T16:36:43.435644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.139239Z","time spent":"1.296399711s","remote":"127.0.0.1:42784","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-07T16:36:43.435658Z","caller":"traceutil/trace.go:171","msg":"trace[1000708507] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15861; }","duration":"965.839464ms","start":"2026-07-07T16:36:42.469812Z","end":"2026-07-07T16:36:43.435651Z","steps":["trace[1000708507] 'agreement among raft nodes before linearized reading' (duration: 965.801813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.469804Z","time spent":"965.873975ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:43.433115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.542891ms","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-07T16:36:43.435761Z","caller":"traceutil/trace.go:171","msg":"trace[83815731] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"967.200117ms","start":"2026-07-07T16:36:42.468551Z","end":"2026-07-07T16:36:43.435751Z","steps":["trace[83815731] 'agreement among raft nodes before linearized reading' (duration: 964.51871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.902285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-07T16:36:43.435784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.468544Z","time spent":"967.233107ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-07T16:36:43.435786Z","caller":"traceutil/trace.go:171","msg":"trace[1529269948] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"965.930756ms","start":"2026-07-07T16:36:42.469849Z","end":"2026-07-07T16:36:43.43578Z","steps":["trace[1529269948] 'agreement among raft nodes before linearized reading' (duration: 965.879515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.469845Z","time spent":"965.962037ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-07T16:36:43.433159Z","caller":"traceutil/trace.go:171","msg":"trace[1829291707] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15861; }","duration":"964.953721ms","start":"2026-07-07T16:36:42.468199Z","end":"2026-07-07T16:36:43.433152Z","steps":["trace[1829291707] 'agreement among raft nodes before linearized reading' (duration: 964.927581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.435852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.468191Z","time spent":"967.656678ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-07T16:36:43.436107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446802752s","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-07T16:36:43.436276Z","caller":"traceutil/trace.go:171","msg":"trace[558199152] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"1.446982276s","start":"2026-07-07T16:36:41.989276Z","end":"2026-07-07T16:36:43.436258Z","steps":["trace[558199152] 'agreement among raft nodes before linearized reading' (duration: 1.445961541s)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.436317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:41.989235Z","time spent":"1.447073198s","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-07T16:36:43.43646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.210255ms","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-07T16:36:43.436498Z","caller":"traceutil/trace.go:171","msg":"trace[1656551283] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15861; }","duration":"556.280107ms","start":"2026-07-07T16:36:42.880209Z","end":"2026-07-07T16:36:43.436489Z","steps":["trace[1656551283] 'agreement among raft nodes before linearized reading' (duration: 553.839336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.436522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:42.880193Z","time spent":"556.324147ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-07T16:36:43.966221Z","caller":"traceutil/trace.go:171","msg":"trace[530316778] transaction","detail":"{read_only:false; response_revision:15863; number_of_response:1; }","duration":"525.62687ms","start":"2026-07-07T16:36:43.440553Z","end":"2026-07-07T16:36:43.96618Z","steps":["trace[530316778] 'process raft request' (duration: 525.354553ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.966245Z","caller":"traceutil/trace.go:171","msg":"trace[669681836] transaction","detail":"{read_only:false; response_revision:15865; number_of_response:1; }","duration":"523.649982ms","start":"2026-07-07T16:36:43.442568Z","end":"2026-07-07T16:36:43.966218Z","steps":["trace[669681836] 'process raft request' (duration: 523.57598ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.966268Z","caller":"traceutil/trace.go:171","msg":"trace[359160606] transaction","detail":"{read_only:false; response_revision:15864; number_of_response:1; }","duration":"523.689452ms","start":"2026-07-07T16:36:43.44254Z","end":"2026-07-07T16:36:43.966229Z","steps":["trace[359160606] 'process raft request' (duration: 523.564229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.966392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.440522Z","time spent":"525.776043ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T16:36:43.966436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.442519Z","time spent":"523.826316ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:36:43.966438Z","caller":"traceutil/trace.go:171","msg":"trace[1772811378] linearizableReadLoop","detail":"{readStateIndex:16417; appliedIndex:16413; }","duration":"455.613512ms","start":"2026-07-07T16:36:43.510797Z","end":"2026-07-07T16:36:43.966411Z","steps":["trace[1772811378] 'read index received' (duration: 455.109989ms)","trace[1772811378] 'applied index is now lower than readState.Index' (duration: 501.583µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:36:43.966578Z","caller":"traceutil/trace.go:171","msg":"trace[120322012] transaction","detail":"{read_only:false; response_revision:15866; number_of_response:1; }","duration":"523.924069ms","start":"2026-07-07T16:36:43.442642Z","end":"2026-07-07T16:36:43.966567Z","steps":["trace[120322012] 'process raft request' (duration: 523.535709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.966593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.383286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:43.966625Z","caller":"traceutil/trace.go:171","msg":"trace[2048123994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15866; }","duration":"521.445368ms","start":"2026-07-07T16:36:43.445169Z","end":"2026-07-07T16:36:43.966615Z","steps":["trace[2048123994] 'agreement among raft nodes before linearized reading' (duration: 521.367465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.966636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.442634Z","time spent":"523.974451ms","remote":"127.0.0.1:43036","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-07T16:36:43.966656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.445133Z","time spent":"521.51662ms","remote":"127.0.0.1:42784","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-07T16:36:43.966771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.824629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-07T16:36:43.966801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.775804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T16:36:43.966832Z","caller":"traceutil/trace.go:171","msg":"trace[471955744] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:15866; }","duration":"453.911491ms","start":"2026-07-07T16:36:43.512905Z","end":"2026-07-07T16:36:43.966816Z","steps":["trace[471955744] 'agreement among raft nodes before linearized reading' (duration: 453.755537ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:36:43.966837Z","caller":"traceutil/trace.go:171","msg":"trace[1775377323] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:15866; }","duration":"219.839805ms","start":"2026-07-07T16:36:43.746985Z","end":"2026-07-07T16:36:43.966825Z","steps":["trace[1775377323] 'agreement among raft nodes before linearized reading' (duration: 219.767613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.966879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.512886Z","time spent":"453.980343ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-07T16:36:43.967479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.507124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:43.967529Z","caller":"traceutil/trace.go:171","msg":"trace[237607176] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15866; }","duration":"520.577636ms","start":"2026-07-07T16:36:43.44694Z","end":"2026-07-07T16:36:43.967517Z","steps":["trace[237607176] 'agreement among raft nodes before linearized reading' (duration: 519.603441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.96763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.446929Z","time spent":"520.636518ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:43.968003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.77129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:43.968066Z","caller":"traceutil/trace.go:171","msg":"trace[1648492731] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15866; }","duration":"522.915194ms","start":"2026-07-07T16:36:43.445136Z","end":"2026-07-07T16:36:43.968051Z","steps":["trace[1648492731] 'agreement among raft nodes before linearized reading' (duration: 521.359265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.968126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.445124Z","time spent":"522.986795ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:43.968516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.035781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:43.968565Z","caller":"traceutil/trace.go:171","msg":"trace[1338548575] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15866; }","duration":"526.118353ms","start":"2026-07-07T16:36:43.442435Z","end":"2026-07-07T16:36:43.968553Z","steps":["trace[1338548575] 'agreement among raft nodes before linearized reading' (duration: 524.314179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.968602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.442415Z","time spent":"526.176244ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:43.968645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.458278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"info","ts":"2026-07-07T16:36:43.968774Z","caller":"traceutil/trace.go:171","msg":"trace[806383905] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15866; }","duration":"521.638842ms","start":"2026-07-07T16:36:43.447111Z","end":"2026-07-07T16:36:43.968749Z","steps":["trace[806383905] 'agreement among raft nodes before linearized reading' (duration: 519.359355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.968904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.118929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:79 size:769652"} {"level":"warn","ts":"2026-07-07T16:36:43.968913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.447106Z","time spent":"521.717113ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-07T16:36:43.968958Z","caller":"traceutil/trace.go:171","msg":"trace[1202095800] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:79; response_revision:15866; }","duration":"521.17621ms","start":"2026-07-07T16:36:43.447769Z","end":"2026-07-07T16:36:43.968945Z","steps":["trace[1202095800] 'agreement among raft nodes before linearized reading' (duration: 518.971777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:36:43.969001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.447751Z","time spent":"521.239392ms","remote":"127.0.0.1:42936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":79,"response size":769676,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-07T16:36:43.966448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T16:36:43.442553Z","time spent":"523.757464ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T16:39:57.512984Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14735} {"level":"info","ts":"2026-07-07T16:39:57.595606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14735,"took":"80.032772ms","hash":3970079825,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":34435072,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-07T16:39:57.595673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3970079825,"revision":14735,"compact-revision":12657} {"level":"info","ts":"2026-07-07T16:42:13.766453Z","caller":"traceutil/trace.go:171","msg":"trace[101343535] transaction","detail":"{read_only:false; response_revision:18313; number_of_response:1; }","duration":"112.990773ms","start":"2026-07-07T16:42:13.653445Z","end":"2026-07-07T16:42:13.766435Z","steps":["trace[101343535] 'process raft request' (duration: 112.764627ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:42:13.766277Z","caller":"traceutil/trace.go:171","msg":"trace[1757735197] transaction","detail":"{read_only:false; response_revision:18312; number_of_response:1; }","duration":"113.099286ms","start":"2026-07-07T16:42:13.653151Z","end":"2026-07-07T16:42:13.766251Z","steps":["trace[1757735197] 'process raft request' (duration: 112.955322ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:42:13.766592Z","caller":"traceutil/trace.go:171","msg":"trace[739553491] transaction","detail":"{read_only:false; response_revision:18314; number_of_response:1; }","duration":"112.160891ms","start":"2026-07-07T16:42:13.654387Z","end":"2026-07-07T16:42:13.766548Z","steps":["trace[739553491] 'process raft request' (duration: 111.980836ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:42:16.751654Z","caller":"traceutil/trace.go:171","msg":"trace[495046705] transaction","detail":"{read_only:false; response_revision:18330; number_of_response:1; }","duration":"110.684642ms","start":"2026-07-07T16:42:16.640941Z","end":"2026-07-07T16:42:16.751626Z","steps":["trace[495046705] 'process raft request' (duration: 110.512907ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:42:20.356634Z","caller":"traceutil/trace.go:171","msg":"trace[1994523714] transaction","detail":"{read_only:false; response_revision:18355; number_of_response:1; }","duration":"109.243348ms","start":"2026-07-07T16:42:20.247363Z","end":"2026-07-07T16:42:20.356606Z","steps":["trace[1994523714] 'process raft request' (duration: 102.513382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:42:20.807639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.065934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-07T16:42:20.807731Z","caller":"traceutil/trace.go:171","msg":"trace[78169318] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:18357; }","duration":"161.164856ms","start":"2026-07-07T16:42:20.646543Z","end":"2026-07-07T16:42:20.807708Z","steps":["trace[78169318] 'range keys from in-memory index tree' (duration: 160.92293ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:42:21.055049Z","caller":"traceutil/trace.go:171","msg":"trace[1862994362] linearizableReadLoop","detail":"{readStateIndex:18993; appliedIndex:18991; }","duration":"129.318863ms","start":"2026-07-07T16:42:20.925697Z","end":"2026-07-07T16:42:21.055016Z","steps":["trace[1862994362] 'read index received' (duration: 125.377495ms)","trace[1862994362] 'applied index is now lower than readState.Index' (duration: 3.940448ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T16:42:21.055206Z","caller":"traceutil/trace.go:171","msg":"trace[127424943] transaction","detail":"{read_only:false; response_revision:18359; number_of_response:1; }","duration":"292.021385ms","start":"2026-07-07T16:42:20.763147Z","end":"2026-07-07T16:42:21.055168Z","steps":["trace[127424943] 'process raft request' (duration: 288.030697ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:42:21.055281Z","caller":"traceutil/trace.go:171","msg":"trace[864545332] transaction","detail":"{read_only:false; response_revision:18360; number_of_response:1; }","duration":"292.027246ms","start":"2026-07-07T16:42:20.763224Z","end":"2026-07-07T16:42:21.055251Z","steps":["trace[864545332] 'process raft request' (duration: 291.693478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T16:42:21.055309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.589809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-07T16:42:21.055386Z","caller":"traceutil/trace.go:171","msg":"trace[1054220980] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:18360; }","duration":"129.711512ms","start":"2026-07-07T16:42:20.925653Z","end":"2026-07-07T16:42:21.055365Z","steps":["trace[1054220980] 'agreement among raft nodes before linearized reading' (duration: 129.475876ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T16:44:57.520167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17392} {"level":"info","ts":"2026-07-07T16:44:57.618971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17392,"took":"95.516437ms","hash":2864632171,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":30953472,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-07T16:44:57.61903Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2864632171,"revision":17392,"compact-revision":14735} {"level":"info","ts":"2026-07-07T16:45:06.906456Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-07T16:45:06.91187Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-07T16:45:06.911964Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-07T16:49:57.525739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19277} {"level":"info","ts":"2026-07-07T16:49:57.640738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19277,"took":"111.817825ms","hash":2528268882,"current-db-size-bytes":57876480,"current-db-size":"58 MB","current-db-size-in-use-bytes":24739840,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-07T16:49:57.640998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2528268882,"revision":19277,"compact-revision":17392}