{"level":"warn","ts":"2026-07-01T10:43:03.792941Z","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-01T10:43:03.793022Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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-01T10:43:03.793087Z","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-01T10:43:03.7931Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-01T10:43:03.793119Z","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-01T10:43:03.793719Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-07-01T10:43:03.793791Z","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.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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-01T10:43:03.804688Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.639696ms"} {"level":"info","ts":"2026-07-01T10:43:03.812051Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-07-01T10:43:03.812169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T10:43:03.812194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-07-01T10:43:03.812211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T10:43:03.812243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-07-01T10:43:03.812287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-07-01T10:43:03.819519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T10:43:03.821491Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T10:43:03.823111Z","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-01T10:43:03.826079Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T10:43:03.826255Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T10:43:03.826197Z","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-01T10:43:03.826383Z","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-01T10:43:03.826408Z","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-01T10:43:03.827439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-07-01T10:43:03.828587Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-07-01T10:43:03.830892Z","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-01T10:43:03.831012Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-01T10:43:03.831056Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-07-01T10:43:03.83135Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T10:43:03.831402Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T10:43:03.913231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T10:43:03.913282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T10:43:03.913324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-07-01T10:43:03.913362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-07-01T10:43:03.913381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-01T10:43:03.913401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-07-01T10:43:03.913428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-07-01T10:43:03.914581Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:03.915782Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T10:43:03.916002Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:03.916141Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:03.916187Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T10:43:03.916207Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:43:03.916383Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T10:43:03.916432Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T10:43:03.916584Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T10:43:03.918933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-07-01T10:43:03.919528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-01T10:43:13.201369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.627328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:43:13.201514Z","caller":"traceutil/trace.go:171","msg":"trace[1127602779] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:337; }","duration":"205.834605ms","start":"2026-07-01T10:43:12.995648Z","end":"2026-07-01T10:43:13.201483Z","steps":["trace[1127602779] 'range keys from in-memory index tree' (duration: 205.531116ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:43:13.361366Z","caller":"traceutil/trace.go:171","msg":"trace[289435390] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"151.72003ms","start":"2026-07-01T10:43:13.209627Z","end":"2026-07-01T10:43:13.361348Z","steps":["trace[289435390] 'process raft request' (duration: 151.625767ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:43:20.074413Z","caller":"traceutil/trace.go:171","msg":"trace[800522818] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"117.905717ms","start":"2026-07-01T10:43:19.956465Z","end":"2026-07-01T10:43:20.074371Z","steps":["trace[800522818] 'process raft request' (duration: 117.756063ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:43:47.598384Z","caller":"traceutil/trace.go:171","msg":"trace[1048367283] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"456.240563ms","start":"2026-07-01T10:43:47.142119Z","end":"2026-07-01T10:43:47.598359Z","steps":["trace[1048367283] 'read index received' (duration: 456.099769ms)","trace[1048367283] 'applied index is now lower than readState.Index' (duration: 139.624µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:43:47.598512Z","caller":"traceutil/trace.go:171","msg":"trace[823398393] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"494.127661ms","start":"2026-07-01T10:43:47.104375Z","end":"2026-07-01T10:43:47.598502Z","steps":["trace[823398393] 'process raft request' (duration: 493.794911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:43:47.59908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.945705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-01T10:43:47.599072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:43:47.104348Z","time spent":"494.188794ms","remote":"127.0.0.1:47640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:43:47.599182Z","caller":"traceutil/trace.go:171","msg":"trace[132350097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"457.115679ms","start":"2026-07-01T10:43:47.142044Z","end":"2026-07-01T10:43:47.59916Z","steps":["trace[132350097] 'agreement among raft nodes before linearized reading' (duration: 456.850491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:43:47.59923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:43:47.142014Z","time spent":"457.206752ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T10:43:47.65526Z","caller":"traceutil/trace.go:171","msg":"trace[1012596326] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"312.344061ms","start":"2026-07-01T10:43:47.342893Z","end":"2026-07-01T10:43:47.655237Z","steps":["trace[1012596326] 'process raft request' (duration: 307.317447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:43:47.65538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:43:47.342865Z","time spent":"312.455645ms","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:43:47.655519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.478027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T10:43:47.655573Z","caller":"traceutil/trace.go:171","msg":"trace[384896782] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:634; }","duration":"417.55883ms","start":"2026-07-01T10:43:47.238Z","end":"2026-07-01T10:43:47.655559Z","steps":["trace[384896782] 'agreement among raft nodes before linearized reading' (duration: 417.277441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:43:47.656088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:43:47.237982Z","time spent":"417.611971ms","remote":"127.0.0.1:47502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"warn","ts":"2026-07-01T10:43:48.133539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.26484ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-01T10:43:48.133771Z","caller":"traceutil/trace.go:171","msg":"trace[2139577239] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"263.2241ms","start":"2026-07-01T10:43:47.870534Z","end":"2026-07-01T10:43:48.133758Z","steps":["trace[2139577239] 'read index received' (duration: 54.703413ms)","trace[2139577239] 'applied index is now lower than readState.Index' (duration: 208.507016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:43:48.133761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:43:47.656971Z","time spent":"476.782331ms","remote":"127.0.0.1:47502","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-01T10:43:48.133906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.365975ms","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-01T10:43:48.133931Z","caller":"traceutil/trace.go:171","msg":"trace[1271742825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"263.436437ms","start":"2026-07-01T10:43:47.870488Z","end":"2026-07-01T10:43:48.133925Z","steps":["trace[1271742825] 'agreement among raft nodes before linearized reading' (duration: 263.309593ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:43:48.133853Z","caller":"traceutil/trace.go:171","msg":"trace[910009874] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"421.894334ms","start":"2026-07-01T10:43:47.711922Z","end":"2026-07-01T10:43:48.133816Z","steps":["trace[910009874] 'process raft request' (duration: 421.723298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:43:48.134112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:43:47.711897Z","time spent":"422.0864ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T10:44:03.668771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.643606ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-01T10:44:29.653582Z","caller":"traceutil/trace.go:171","msg":"trace[2067898956] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"222.465142ms","start":"2026-07-01T10:44:29.431085Z","end":"2026-07-01T10:44:29.65355Z","steps":["trace[2067898956] 'process raft request' (duration: 208.423033ms)","trace[2067898956] 'compare' (duration: 13.71271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:45:17.046489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.281321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:45:17.046591Z","caller":"traceutil/trace.go:171","msg":"trace[340909247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1862; }","duration":"311.438405ms","start":"2026-07-01T10:45:16.735133Z","end":"2026-07-01T10:45:17.046571Z","steps":["trace[340909247] 'range keys from in-memory index tree' (duration: 310.980472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:45:17.046585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.773501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T10:45:17.046699Z","caller":"traceutil/trace.go:171","msg":"trace[1167057732] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1862; }","duration":"119.962947ms","start":"2026-07-01T10:45:16.926707Z","end":"2026-07-01T10:45:17.04667Z","steps":["trace[1167057732] 'count revisions from in-memory index tree' (duration: 119.616457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:45:17.046629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:45:16.735111Z","time spent":"311.509738ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T10:45:29.303651Z","caller":"traceutil/trace.go:171","msg":"trace[955912692] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"121.760678ms","start":"2026-07-01T10:45:29.181853Z","end":"2026-07-01T10:45:29.303614Z","steps":["trace[955912692] 'process raft request' (duration: 121.562593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.347552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.40507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-07-01T10:46:07.347666Z","caller":"traceutil/trace.go:171","msg":"trace[691847995] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2183; }","duration":"163.570985ms","start":"2026-07-01T10:46:07.184073Z","end":"2026-07-01T10:46:07.347644Z","steps":["trace[691847995] 'range keys from in-memory index tree' (duration: 163.217045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.347691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.819942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:46:07.347775Z","caller":"traceutil/trace.go:171","msg":"trace[1705467523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2183; }","duration":"103.958868ms","start":"2026-07-01T10:46:07.243796Z","end":"2026-07-01T10:46:07.347755Z","steps":["trace[1705467523] 'range keys from in-memory index tree' (duration: 103.70397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.348123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.702771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T10:46:07.348169Z","caller":"traceutil/trace.go:171","msg":"trace[120357780] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:2183; }","duration":"102.749012ms","start":"2026-07-01T10:46:07.245391Z","end":"2026-07-01T10:46:07.34814Z","steps":["trace[120357780] 'range keys from in-memory index tree' (duration: 102.585718ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:46:07.736638Z","caller":"traceutil/trace.go:171","msg":"trace[1989003542] linearizableReadLoop","detail":"{readStateIndex:2265; appliedIndex:2263; }","duration":"250.970603ms","start":"2026-07-01T10:46:07.485638Z","end":"2026-07-01T10:46:07.736609Z","steps":["trace[1989003542] 'read index received' (duration: 48.722283ms)","trace[1989003542] 'applied index is now lower than readState.Index' (duration: 202.24681ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:46:07.736821Z","caller":"traceutil/trace.go:171","msg":"trace[422627505] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"381.132406ms","start":"2026-07-01T10:46:07.355625Z","end":"2026-07-01T10:46:07.736757Z","steps":["trace[422627505] 'process raft request' (duration: 380.723884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.736996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.275692ms","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-01T10:46:07.737121Z","caller":"traceutil/trace.go:171","msg":"trace[278927283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2184; }","duration":"251.53365ms","start":"2026-07-01T10:46:07.485568Z","end":"2026-07-01T10:46:07.737102Z","steps":["trace[278927283] 'agreement among raft nodes before linearized reading' (duration: 251.205079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.737018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:46:07.355595Z","time spent":"381.324601ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T10:46:07.737494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.533531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-01T10:46:07.737483Z","caller":"traceutil/trace.go:171","msg":"trace[1379296985] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"202.028093ms","start":"2026-07-01T10:46:07.535402Z","end":"2026-07-01T10:46:07.73743Z","steps":["trace[1379296985] 'process raft request' (duration: 201.704074ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:46:07.737546Z","caller":"traceutil/trace.go:171","msg":"trace[1517319606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2186; }","duration":"252.255961ms","start":"2026-07-01T10:46:07.485276Z","end":"2026-07-01T10:46:07.737532Z","steps":["trace[1517319606] 'agreement among raft nodes before linearized reading' (duration: 252.023264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:46:07.737733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.746995ms","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-01T10:46:07.737858Z","caller":"traceutil/trace.go:171","msg":"trace[303514410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2186; }","duration":"226.975432ms","start":"2026-07-01T10:46:07.510851Z","end":"2026-07-01T10:46:07.737826Z","steps":["trace[303514410] 'agreement among raft nodes before linearized reading' (duration: 226.665544ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:47:30.237564Z","caller":"traceutil/trace.go:171","msg":"trace[1366786587] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"142.435167ms","start":"2026-07-01T10:47:30.095101Z","end":"2026-07-01T10:47:30.237537Z","steps":["trace[1366786587] 'process raft request' (duration: 142.288452ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:47:30.251438Z","caller":"traceutil/trace.go:171","msg":"trace[1378537523] linearizableReadLoop","detail":"{readStateIndex:2932; appliedIndex:2930; }","duration":"116.538662ms","start":"2026-07-01T10:47:30.134861Z","end":"2026-07-01T10:47:30.2514Z","steps":["trace[1378537523] 'read index received' (duration: 102.546234ms)","trace[1378537523] 'applied index is now lower than readState.Index' (duration: 13.991488ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:47:30.251499Z","caller":"traceutil/trace.go:171","msg":"trace[1835494376] transaction","detail":"{read_only:false; response_revision:2827; number_of_response:1; }","duration":"130.264913ms","start":"2026-07-01T10:47:30.121196Z","end":"2026-07-01T10:47:30.251461Z","steps":["trace[1835494376] 'process raft request' (duration: 130.095658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:47:30.251696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.81614ms","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-01T10:47:30.251741Z","caller":"traceutil/trace.go:171","msg":"trace[825307824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2827; }","duration":"116.910453ms","start":"2026-07-01T10:47:30.13482Z","end":"2026-07-01T10:47:30.25173Z","steps":["trace[825307824] 'agreement among raft nodes before linearized reading' (duration: 116.733027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:49:55.682758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.295313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-07-01T10:49:55.682825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.174172ms","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-01T10:49:55.682859Z","caller":"traceutil/trace.go:171","msg":"trace[1508279906] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3799; }","duration":"136.457108ms","start":"2026-07-01T10:49:55.546384Z","end":"2026-07-01T10:49:55.682841Z","steps":["trace[1508279906] 'range keys from in-memory index tree' (duration: 136.011275ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:49:55.682927Z","caller":"traceutil/trace.go:171","msg":"trace[1615319329] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3799; }","duration":"106.322055ms","start":"2026-07-01T10:49:55.576568Z","end":"2026-07-01T10:49:55.68289Z","steps":["trace[1615319329] 'range keys from in-memory index tree' (duration: 105.946935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:51:23.381918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.831316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T10:51:23.382143Z","caller":"traceutil/trace.go:171","msg":"trace[409657409] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"141.427016ms","start":"2026-07-01T10:51:23.240673Z","end":"2026-07-01T10:51:23.3821Z","steps":["trace[409657409] 'read index received' (duration: 38.472µs)","trace[409657409] 'applied index is now lower than readState.Index' (duration: 141.384694ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:23.382172Z","caller":"traceutil/trace.go:171","msg":"trace[1431170999] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"286.27551ms","start":"2026-07-01T10:51:23.095854Z","end":"2026-07-01T10:51:23.38213Z","steps":["trace[1431170999] 'process raft request' (duration: 128.166307ms)","trace[1431170999] 'compare' (duration: 157.634789ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:23.382283Z","caller":"traceutil/trace.go:171","msg":"trace[1132685142] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"103.199548ms","start":"2026-07-01T10:51:23.278901Z","end":"2026-07-01T10:51:23.3821Z","steps":["trace[1132685142] 'process raft request' (duration: 103.128946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:51:23.382297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.594241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:51:23.383214Z","caller":"traceutil/trace.go:171","msg":"trace[171303718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4986; }","duration":"142.555789ms","start":"2026-07-01T10:51:23.240639Z","end":"2026-07-01T10:51:23.383195Z","steps":["trace[171303718] 'agreement among raft nodes before linearized reading' (duration: 141.55301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:51:23.38251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.954675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c5c8045-f0c6-4369-8e18-dfab2c8abae7\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-01T10:51:23.383415Z","caller":"traceutil/trace.go:171","msg":"trace[1966875566] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6c5c8045-f0c6-4369-8e18-dfab2c8abae7; range_end:; response_count:1; response_revision:4986; }","duration":"136.937244ms","start":"2026-07-01T10:51:23.246465Z","end":"2026-07-01T10:51:23.383402Z","steps":["trace[1966875566] 'agreement among raft nodes before linearized reading' (duration: 135.833792ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:23.706713Z","caller":"traceutil/trace.go:171","msg":"trace[1563707435] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"119.892475ms","start":"2026-07-01T10:51:23.586786Z","end":"2026-07-01T10:51:23.706679Z","steps":["trace[1563707435] 'process raft request' (duration: 22.713364ms)","trace[1563707435] 'compare' (duration: 97.006376ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:36.546127Z","caller":"traceutil/trace.go:171","msg":"trace[1262175007] transaction","detail":"{read_only:false; response_revision:5235; number_of_response:1; }","duration":"143.328371ms","start":"2026-07-01T10:51:36.402772Z","end":"2026-07-01T10:51:36.5461Z","steps":["trace[1262175007] 'process raft request' (duration: 143.177396ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:36.546538Z","caller":"traceutil/trace.go:171","msg":"trace[364409473] linearizableReadLoop","detail":"{readStateIndex:5429; appliedIndex:5429; }","duration":"134.0394ms","start":"2026-07-01T10:51:36.412477Z","end":"2026-07-01T10:51:36.546517Z","steps":["trace[364409473] 'read index received' (duration: 134.028899ms)","trace[364409473] 'applied index is now lower than readState.Index' (duration: 8.221µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:51:36.546714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.218045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-n2kbp\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-07-01T10:51:36.546737Z","caller":"traceutil/trace.go:171","msg":"trace[1210965396] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-n2kbp; range_end:; response_count:1; response_revision:5235; }","duration":"134.284307ms","start":"2026-07-01T10:51:36.412445Z","end":"2026-07-01T10:51:36.54673Z","steps":["trace[1210965396] 'agreement among raft nodes before linearized reading' (duration: 134.146173ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:36.632968Z","caller":"traceutil/trace.go:171","msg":"trace[925549670] transaction","detail":"{read_only:false; response_revision:5236; number_of_response:1; }","duration":"201.993066ms","start":"2026-07-01T10:51:36.43094Z","end":"2026-07-01T10:51:36.632933Z","steps":["trace[925549670] 'process raft request' (duration: 175.870053ms)","trace[925549670] 'compare' (duration: 25.821425ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:44.760654Z","caller":"traceutil/trace.go:171","msg":"trace[260550680] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"259.049864ms","start":"2026-07-01T10:51:44.501569Z","end":"2026-07-01T10:51:44.760619Z","steps":["trace[260550680] 'process raft request' (duration: 258.784636ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.977399Z","caller":"traceutil/trace.go:171","msg":"trace[870851846] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"212.061841ms","start":"2026-07-01T10:51:44.765304Z","end":"2026-07-01T10:51:44.977365Z","steps":["trace[870851846] 'process raft request' (duration: 133.07447ms)","trace[870851846] 'compare' (duration: 78.750164ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:51:44.977793Z","caller":"traceutil/trace.go:171","msg":"trace[1084788520] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"211.528495ms","start":"2026-07-01T10:51:44.766228Z","end":"2026-07-01T10:51:44.977757Z","steps":["trace[1084788520] 'process raft request' (duration: 211.35705ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.977867Z","caller":"traceutil/trace.go:171","msg":"trace[1885916531] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"211.434132ms","start":"2026-07-01T10:51:44.766404Z","end":"2026-07-01T10:51:44.977838Z","steps":["trace[1885916531] 'process raft request' (duration: 211.322439ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.977878Z","caller":"traceutil/trace.go:171","msg":"trace[2074348536] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"211.901686ms","start":"2026-07-01T10:51:44.765785Z","end":"2026-07-01T10:51:44.977686Z","steps":["trace[2074348536] 'process raft request' (duration: 211.510075ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.977992Z","caller":"traceutil/trace.go:171","msg":"trace[1780205434] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"211.497005ms","start":"2026-07-01T10:51:44.766489Z","end":"2026-07-01T10:51:44.977986Z","steps":["trace[1780205434] 'process raft request' (duration: 211.271718ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.978034Z","caller":"traceutil/trace.go:171","msg":"trace[1134940105] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"211.623118ms","start":"2026-07-01T10:51:44.766394Z","end":"2026-07-01T10:51:44.978017Z","steps":["trace[1134940105] 'process raft request' (duration: 211.280728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:51:44.977964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.213623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T10:51:44.97813Z","caller":"traceutil/trace.go:171","msg":"trace[719176414] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"210.649339ms","start":"2026-07-01T10:51:44.767475Z","end":"2026-07-01T10:51:44.978125Z","steps":["trace[719176414] 'process raft request' (duration: 210.308959ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.978129Z","caller":"traceutil/trace.go:171","msg":"trace[817996457] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:5417; }","duration":"109.431309ms","start":"2026-07-01T10:51:44.868683Z","end":"2026-07-01T10:51:44.978114Z","steps":["trace[817996457] 'agreement among raft nodes before linearized reading' (duration: 109.236274ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.978163Z","caller":"traceutil/trace.go:171","msg":"trace[810191628] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"207.532248ms","start":"2026-07-01T10:51:44.770613Z","end":"2026-07-01T10:51:44.978145Z","steps":["trace[810191628] 'process raft request' (duration: 207.205219ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:51:44.977881Z","caller":"traceutil/trace.go:171","msg":"trace[357286190] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5607; }","duration":"109.11702ms","start":"2026-07-01T10:51:44.868747Z","end":"2026-07-01T10:51:44.977864Z","steps":["trace[357286190] 'read index received' (duration: 29.647156ms)","trace[357286190] 'applied index is now lower than readState.Index' (duration: 79.469194ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:53:04.1271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-07-01T10:53:04.20785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"78.438534ms","hash":1717195938,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":32108544,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T10:53:04.207926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1717195938,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-07-01T10:57:52.335151Z","caller":"traceutil/trace.go:171","msg":"trace[798127510] transaction","detail":"{read_only:false; response_revision:8250; number_of_response:1; }","duration":"321.968577ms","start":"2026-07-01T10:57:52.013153Z","end":"2026-07-01T10:57:52.335122Z","steps":["trace[798127510] 'process raft request' (duration: 321.783892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:57:52.335322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T10:57:52.013135Z","time spent":"322.106012ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T10:58:04.182792Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6142} {"level":"info","ts":"2026-07-01T10:58:04.503544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6142,"took":"318.878327ms","hash":2421270273,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":23449600,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-01T10:58:04.503629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2421270273,"revision":6142,"compact-revision":3013} {"level":"info","ts":"2026-07-01T10:58:04.685247Z","caller":"traceutil/trace.go:171","msg":"trace[2016622864] transaction","detail":"{read_only:false; response_revision:8316; number_of_response:1; }","duration":"132.404546ms","start":"2026-07-01T10:58:04.552812Z","end":"2026-07-01T10:58:04.685217Z","steps":["trace[2016622864] 'process raft request' (duration: 132.19727ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:04.778086Z","caller":"traceutil/trace.go:171","msg":"trace[2015081489] transaction","detail":"{read_only:false; response_revision:8317; number_of_response:1; }","duration":"223.804678ms","start":"2026-07-01T10:58:04.554229Z","end":"2026-07-01T10:58:04.778034Z","steps":["trace[2015081489] 'process raft request' (duration: 199.80904ms)","trace[2015081489] 'compare' (duration: 23.817493ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:58:05.605752Z","caller":"traceutil/trace.go:171","msg":"trace[1740457655] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"101.433894ms","start":"2026-07-01T10:58:05.504279Z","end":"2026-07-01T10:58:05.605712Z","steps":["trace[1740457655] 'process raft request' (duration: 56.429044ms)","trace[1740457655] 'compare' (duration: 44.885657ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:58:33.478848Z","caller":"traceutil/trace.go:171","msg":"trace[1960930367] linearizableReadLoop","detail":"{readStateIndex:8827; appliedIndex:8826; }","duration":"111.087786ms","start":"2026-07-01T10:58:33.367744Z","end":"2026-07-01T10:58:33.478831Z","steps":["trace[1960930367] 'read index received' (duration: 110.990303ms)","trace[1960930367] 'applied index is now lower than readState.Index' (duration: 96.853µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:58:33.479006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.25009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bf8h9\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-07-01T10:58:33.479084Z","caller":"traceutil/trace.go:171","msg":"trace[944476970] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bf8h9; range_end:; response_count:1; response_revision:8525; }","duration":"111.374894ms","start":"2026-07-01T10:58:33.367701Z","end":"2026-07-01T10:58:33.479075Z","steps":["trace[944476970] 'agreement among raft nodes before linearized reading' (duration: 111.2196ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:33.47909Z","caller":"traceutil/trace.go:171","msg":"trace[2125644855] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"229.417282ms","start":"2026-07-01T10:58:33.249653Z","end":"2026-07-01T10:58:33.479071Z","steps":["trace[2125644855] 'process raft request' (duration: 228.895337ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:33.627997Z","caller":"traceutil/trace.go:171","msg":"trace[1176653830] linearizableReadLoop","detail":"{readStateIndex:8828; appliedIndex:8827; }","duration":"142.643754ms","start":"2026-07-01T10:58:33.485322Z","end":"2026-07-01T10:58:33.627966Z","steps":["trace[1176653830] 'read index received' (duration: 142.367446ms)","trace[1176653830] 'applied index is now lower than readState.Index' (duration: 275.038µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T10:58:33.628678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.322535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228863"} {"level":"info","ts":"2026-07-01T10:58:33.628736Z","caller":"traceutil/trace.go:171","msg":"trace[358531480] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8526; }","duration":"143.438677ms","start":"2026-07-01T10:58:33.485285Z","end":"2026-07-01T10:58:33.628723Z","steps":["trace[358531480] 'agreement among raft nodes before linearized reading' (duration: 142.817619ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:33.629244Z","caller":"traceutil/trace.go:171","msg":"trace[437701793] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"153.093709ms","start":"2026-07-01T10:58:33.476133Z","end":"2026-07-01T10:58:33.629227Z","steps":["trace[437701793] 'process raft request' (duration: 151.644557ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:46.179696Z","caller":"traceutil/trace.go:171","msg":"trace[746152280] transaction","detail":"{read_only:false; response_revision:8636; number_of_response:1; }","duration":"103.382018ms","start":"2026-07-01T10:58:46.076269Z","end":"2026-07-01T10:58:46.179651Z","steps":["trace[746152280] 'process raft request' (duration: 103.218514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T10:58:46.180136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.232793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19383"} {"level":"info","ts":"2026-07-01T10:58:46.180224Z","caller":"traceutil/trace.go:171","msg":"trace[1647020799] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8636; }","duration":"103.351837ms","start":"2026-07-01T10:58:46.076852Z","end":"2026-07-01T10:58:46.180204Z","steps":["trace[1647020799] 'agreement among raft nodes before linearized reading' (duration: 103.14198ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T10:58:46.179636Z","caller":"traceutil/trace.go:171","msg":"trace[73049950] linearizableReadLoop","detail":"{readStateIndex:8944; appliedIndex:8943; }","duration":"102.735028ms","start":"2026-07-01T10:58:46.076881Z","end":"2026-07-01T10:58:46.179616Z","steps":["trace[73049950] 'read index received' (duration: 102.620905ms)","trace[73049950] 'applied index is now lower than readState.Index' (duration: 113.283µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T10:59:36.99587Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c9.wal"} {"level":"info","ts":"2026-07-01T11:00:40.872779Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:00:40.878546Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T11:00:40.878631Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T11:03:04.190133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8314} {"level":"info","ts":"2026-07-01T11:03:04.26597Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8314,"took":"73.991665ms","hash":4267141960,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":25653248,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-01T11:03:04.266049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4267141960,"revision":8314,"compact-revision":6142} {"level":"info","ts":"2026-07-01T11:08:04.19679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10805} {"level":"info","ts":"2026-07-01T11:08:04.273272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10805,"took":"74.105567ms","hash":199868895,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":28520448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-01T11:08:04.273332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":199868895,"revision":10805,"compact-revision":8314} {"level":"info","ts":"2026-07-01T11:10:11.936559Z","caller":"traceutil/trace.go:171","msg":"trace[613867839] transaction","detail":"{read_only:false; response_revision:13844; number_of_response:1; }","duration":"102.428557ms","start":"2026-07-01T11:10:11.8341Z","end":"2026-07-01T11:10:11.936528Z","steps":["trace[613867839] 'process raft request' (duration: 101.144139ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:10:19.770752Z","caller":"traceutil/trace.go:171","msg":"trace[39292759] transaction","detail":"{read_only:false; response_revision:13884; number_of_response:1; }","duration":"121.043578ms","start":"2026-07-01T11:10:19.64967Z","end":"2026-07-01T11:10:19.770713Z","steps":["trace[39292759] 'process raft request' (duration: 93.9868ms)","trace[39292759] 'compare' (duration: 26.822152ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:10:27.735751Z","caller":"traceutil/trace.go:171","msg":"trace[157717694] transaction","detail":"{read_only:false; response_revision:13941; number_of_response:1; }","duration":"181.281826ms","start":"2026-07-01T11:10:27.55444Z","end":"2026-07-01T11:10:27.735722Z","steps":["trace[157717694] 'process raft request' (duration: 180.707459ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:10:27.997204Z","caller":"traceutil/trace.go:171","msg":"trace[302892728] transaction","detail":"{read_only:false; response_revision:13942; number_of_response:1; }","duration":"257.176868ms","start":"2026-07-01T11:10:27.739999Z","end":"2026-07-01T11:10:27.997176Z","steps":["trace[302892728] 'process raft request' (duration: 257.028034ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:10:27.997896Z","caller":"traceutil/trace.go:171","msg":"trace[122033437] transaction","detail":"{read_only:false; response_revision:13943; number_of_response:1; }","duration":"202.892425ms","start":"2026-07-01T11:10:27.794978Z","end":"2026-07-01T11:10:27.99787Z","steps":["trace[122033437] 'process raft request' (duration: 202.770111ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:10:37.448031Z","caller":"traceutil/trace.go:171","msg":"trace[665160276] linearizableReadLoop","detail":"{readStateIndex:14584; appliedIndex:14583; }","duration":"279.865299ms","start":"2026-07-01T11:10:37.168144Z","end":"2026-07-01T11:10:37.44801Z","steps":["trace[665160276] 'read index received' (duration: 279.665623ms)","trace[665160276] 'applied index is now lower than readState.Index' (duration: 198.506µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:10:37.448133Z","caller":"traceutil/trace.go:171","msg":"trace[1088416911] transaction","detail":"{read_only:false; response_revision:14103; number_of_response:1; }","duration":"301.215972ms","start":"2026-07-01T11:10:37.146882Z","end":"2026-07-01T11:10:37.448098Z","steps":["trace[1088416911] 'process raft request' (duration: 300.974205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:10:37.448304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.066425ms","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-01T11:10:37.448392Z","caller":"traceutil/trace.go:171","msg":"trace[821673421] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14103; }","duration":"280.224539ms","start":"2026-07-01T11:10:37.168119Z","end":"2026-07-01T11:10:37.448344Z","steps":["trace[821673421] 'agreement among raft nodes before linearized reading' (duration: 280.013643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:10:37.448326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:10:37.146857Z","time spent":"301.369897ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:10:37.451605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.065486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:10:37.45169Z","caller":"traceutil/trace.go:171","msg":"trace[1976380755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14104; }","duration":"208.202319ms","start":"2026-07-01T11:10:37.243469Z","end":"2026-07-01T11:10:37.451672Z","steps":["trace[1976380755] 'agreement among raft nodes before linearized reading' (duration: 208.051985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:10:37.451972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.888473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T11:10:37.452123Z","caller":"traceutil/trace.go:171","msg":"trace[328745009] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:14104; }","duration":"123.086759ms","start":"2026-07-01T11:10:37.329011Z","end":"2026-07-01T11:10:37.452097Z","steps":["trace[328745009] 'agreement among raft nodes before linearized reading' (duration: 122.79356ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:12:10.664941Z","caller":"traceutil/trace.go:171","msg":"trace[917691211] linearizableReadLoop","detail":"{readStateIndex:15674; appliedIndex:15673; }","duration":"290.719626ms","start":"2026-07-01T11:12:10.37419Z","end":"2026-07-01T11:12:10.664909Z","steps":["trace[917691211] 'read index received' (duration: 290.576172ms)","trace[917691211] 'applied index is now lower than readState.Index' (duration: 141.664µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:12:10.665034Z","caller":"traceutil/trace.go:171","msg":"trace[1076808607] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"304.855388ms","start":"2026-07-01T11:12:10.360122Z","end":"2026-07-01T11:12:10.664977Z","steps":["trace[1076808607] 'process raft request' (duration: 304.55975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.665155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.972973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:12:10.665239Z","caller":"traceutil/trace.go:171","msg":"trace[1141894412] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15158; }","duration":"291.116797ms","start":"2026-07-01T11:12:10.374104Z","end":"2026-07-01T11:12:10.665221Z","steps":["trace[1141894412] 'agreement among raft nodes before linearized reading' (duration: 290.961703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.665251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:12:10.360098Z","time spent":"305.055204ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:12:10.808059Z","caller":"traceutil/trace.go:171","msg":"trace[1150810719] linearizableReadLoop","detail":"{readStateIndex:15675; appliedIndex:15674; }","duration":"142.995439ms","start":"2026-07-01T11:12:10.665038Z","end":"2026-07-01T11:12:10.808033Z","steps":["trace[1150810719] 'read index received' (duration: 132.177374ms)","trace[1150810719] 'applied index is now lower than readState.Index' (duration: 10.816985ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:12:10.808311Z","caller":"traceutil/trace.go:171","msg":"trace[1687972499] transaction","detail":"{read_only:false; response_revision:15159; number_of_response:1; }","duration":"307.008631ms","start":"2026-07-01T11:12:10.501287Z","end":"2026-07-01T11:12:10.808296Z","steps":["trace[1687972499] 'process raft request' (duration: 295.933599ms)","trace[1687972499] 'compare' (duration: 10.706542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:12:10.808421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:12:10.501268Z","time spent":"307.082493ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:12:10.808761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.639263ms","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-01T11:12:10.808812Z","caller":"traceutil/trace.go:171","msg":"trace[1527924632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15159; }","duration":"266.720166ms","start":"2026-07-01T11:12:10.542078Z","end":"2026-07-01T11:12:10.808798Z","steps":["trace[1527924632] 'agreement among raft nodes before linearized reading' (duration: 266.564491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.809074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.661095ms","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-01T11:12:10.809119Z","caller":"traceutil/trace.go:171","msg":"trace[361108663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15159; }","duration":"324.750428ms","start":"2026-07-01T11:12:10.484353Z","end":"2026-07-01T11:12:10.809104Z","steps":["trace[361108663] 'agreement among raft nodes before linearized reading' (duration: 324.633704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.809159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:12:10.484329Z","time spent":"324.8183ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T11:12:10.809432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.629843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-01T11:12:10.809481Z","caller":"traceutil/trace.go:171","msg":"trace[431213063] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15159; }","duration":"432.699165ms","start":"2026-07-01T11:12:10.376772Z","end":"2026-07-01T11:12:10.809471Z","steps":["trace[431213063] 'agreement among raft nodes before linearized reading' (duration: 432.556481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.809509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:12:10.376763Z","time spent":"432.739857ms","remote":"127.0.0.1:47676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-01T11:12:10.80976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.769076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-01T11:12:10.809801Z","caller":"traceutil/trace.go:171","msg":"trace[1832122994] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15159; }","duration":"433.842789ms","start":"2026-07-01T11:12:10.37595Z","end":"2026-07-01T11:12:10.809793Z","steps":["trace[1832122994] 'agreement among raft nodes before linearized reading' (duration: 433.699104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.809828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:12:10.375934Z","time spent":"433.88754ms","remote":"127.0.0.1:47622","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-01T11:12:10.810016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.346414ms","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-01T11:12:10.810052Z","caller":"traceutil/trace.go:171","msg":"trace[1665025385] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15159; }","duration":"434.401645ms","start":"2026-07-01T11:12:10.375642Z","end":"2026-07-01T11:12:10.810044Z","steps":["trace[1665025385] 'agreement among raft nodes before linearized reading' (duration: 434.310662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.810077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:12:10.375625Z","time spent":"434.445647ms","remote":"127.0.0.1:47622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":703,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-07-01T11:12:10.810209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.875428ms","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-01T11:12:10.810244Z","caller":"traceutil/trace.go:171","msg":"trace[393739327] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15159; }","duration":"435.95528ms","start":"2026-07-01T11:12:10.374281Z","end":"2026-07-01T11:12:10.810236Z","steps":["trace[393739327] 'agreement among raft nodes before linearized reading' (duration: 435.901889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:12:10.810268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:12:10.374259Z","time spent":"436.003612ms","remote":"127.0.0.1:47676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-01T11:12:10.810461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.041405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T11:12:10.810656Z","caller":"traceutil/trace.go:171","msg":"trace[2005170265] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:15159; }","duration":"126.283132ms","start":"2026-07-01T11:12:10.68435Z","end":"2026-07-01T11:12:10.810633Z","steps":["trace[2005170265] 'agreement among raft nodes before linearized reading' (duration: 126.009884ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:13:04.202721Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13012} {"level":"info","ts":"2026-07-01T11:13:04.278679Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13012,"took":"73.460272ms","hash":3868928356,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":31514624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T11:13:04.278768Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3868928356,"revision":13012,"compact-revision":10805} {"level":"info","ts":"2026-07-01T11:18:01.720751Z","caller":"traceutil/trace.go:171","msg":"trace[496345047] transaction","detail":"{read_only:false; response_revision:17833; number_of_response:1; }","duration":"109.392031ms","start":"2026-07-01T11:18:01.611334Z","end":"2026-07-01T11:18:01.720726Z","steps":["trace[496345047] 'process raft request' (duration: 109.266157ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:02.910906Z","caller":"traceutil/trace.go:171","msg":"trace[546106353] transaction","detail":"{read_only:false; response_revision:17838; number_of_response:1; }","duration":"198.474681ms","start":"2026-07-01T11:18:02.71234Z","end":"2026-07-01T11:18:02.910815Z","steps":["trace[546106353] 'process raft request' (duration: 198.311805ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:03.189541Z","caller":"traceutil/trace.go:171","msg":"trace[70601315] transaction","detail":"{read_only:false; response_revision:17839; number_of_response:1; }","duration":"222.682157ms","start":"2026-07-01T11:18:02.966837Z","end":"2026-07-01T11:18:03.189519Z","steps":["trace[70601315] 'process raft request' (duration: 206.595787ms)","trace[70601315] 'compare' (duration: 15.882054ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:18:03.200055Z","caller":"traceutil/trace.go:171","msg":"trace[820316899] linearizableReadLoop","detail":"{readStateIndex:18443; appliedIndex:18441; }","duration":"205.671031ms","start":"2026-07-01T11:18:02.994368Z","end":"2026-07-01T11:18:03.200039Z","steps":["trace[820316899] 'read index received' (duration: 179.078914ms)","trace[820316899] 'applied index is now lower than readState.Index' (duration: 26.590967ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:18:03.200131Z","caller":"traceutil/trace.go:171","msg":"trace[1756632732] transaction","detail":"{read_only:false; response_revision:17840; number_of_response:1; }","duration":"230.229196ms","start":"2026-07-01T11:18:02.969873Z","end":"2026-07-01T11:18:03.200102Z","steps":["trace[1756632732] 'process raft request' (duration: 230.079092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:03.200244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.836925ms","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:8"} {"level":"info","ts":"2026-07-01T11:18:03.200321Z","caller":"traceutil/trace.go:171","msg":"trace[660487996] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17840; }","duration":"205.968699ms","start":"2026-07-01T11:18:02.994335Z","end":"2026-07-01T11:18:03.200304Z","steps":["trace[660487996] 'agreement among raft nodes before linearized reading' (duration: 205.796094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:03.200339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.995721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-07-01T11:18:03.200379Z","caller":"traceutil/trace.go:171","msg":"trace[447186230] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:17840; }","duration":"180.103984ms","start":"2026-07-01T11:18:03.020261Z","end":"2026-07-01T11:18:03.200365Z","steps":["trace[447186230] 'agreement among raft nodes before linearized reading' (duration: 179.905878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:18:03.200374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.166763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-g87lb\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-07-01T11:18:03.200412Z","caller":"traceutil/trace.go:171","msg":"trace[691797736] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-g87lb; range_end:; response_count:1; response_revision:17840; }","duration":"136.245605ms","start":"2026-07-01T11:18:03.064157Z","end":"2026-07-01T11:18:03.200403Z","steps":["trace[691797736] 'agreement among raft nodes before linearized reading' (duration: 136.130982ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:18:04.217332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15498} {"level":"info","ts":"2026-07-01T11:18:04.370424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15498,"took":"150.025888ms","hash":2096144288,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":32604160,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-01T11:18:04.3705Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2096144288,"revision":15498,"compact-revision":13012} {"level":"info","ts":"2026-07-01T11:21:06.512952Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:21:06.519569Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-01T11:21:06.519658Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-01T11:23:04.225674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17848} {"level":"info","ts":"2026-07-01T11:23:04.321223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17848,"took":"91.857948ms","hash":92710743,"current-db-size-bytes":58236928,"current-db-size":"58 MB","current-db-size-in-use-bytes":34693120,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-01T11:23:04.321337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":92710743,"revision":17848,"compact-revision":15498} {"level":"info","ts":"2026-07-01T11:23:19.133297Z","caller":"traceutil/trace.go:171","msg":"trace[138429428] transaction","detail":"{read_only:false; response_revision:20271; number_of_response:1; }","duration":"108.878174ms","start":"2026-07-01T11:23:19.024388Z","end":"2026-07-01T11:23:19.133266Z","steps":["trace[138429428] 'process raft request' (duration: 108.663318ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:23:19.345963Z","caller":"traceutil/trace.go:171","msg":"trace[952211593] linearizableReadLoop","detail":"{readStateIndex:20957; appliedIndex:20956; }","duration":"196.88262ms","start":"2026-07-01T11:23:19.149053Z","end":"2026-07-01T11:23:19.345936Z","steps":["trace[952211593] 'read index received' (duration: 122.314855ms)","trace[952211593] 'applied index is now lower than readState.Index' (duration: 74.566525ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:23:19.346094Z","caller":"traceutil/trace.go:171","msg":"trace[966402417] transaction","detail":"{read_only:false; response_revision:20272; number_of_response:1; }","duration":"218.101968ms","start":"2026-07-01T11:23:19.127978Z","end":"2026-07-01T11:23:19.34608Z","steps":["trace[966402417] 'process raft request' (duration: 143.470742ms)","trace[966402417] 'compare' (duration: 74.322507ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:23:19.346344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.941791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T11:23:19.346417Z","caller":"traceutil/trace.go:171","msg":"trace[516984624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20272; }","duration":"103.061474ms","start":"2026-07-01T11:23:19.243338Z","end":"2026-07-01T11:23:19.346399Z","steps":["trace[516984624] 'agreement among raft nodes before linearized reading' (duration: 102.806637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:23:19.3468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.92391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:23:19.347279Z","caller":"traceutil/trace.go:171","msg":"trace[1501115734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20272; }","duration":"103.447135ms","start":"2026-07-01T11:23:19.243809Z","end":"2026-07-01T11:23:19.347257Z","steps":["trace[1501115734] 'agreement among raft nodes before linearized reading' (duration: 102.898519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:23:19.346797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.715293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-01T11:23:19.347617Z","caller":"traceutil/trace.go:171","msg":"trace[1259249225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20272; }","duration":"198.526268ms","start":"2026-07-01T11:23:19.149018Z","end":"2026-07-01T11:23:19.347544Z","steps":["trace[1259249225] 'agreement among raft nodes before linearized reading' (duration: 197.59459ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:23:19.596561Z","caller":"traceutil/trace.go:171","msg":"trace[1019755271] linearizableReadLoop","detail":"{readStateIndex:20959; appliedIndex:20957; }","duration":"185.992932ms","start":"2026-07-01T11:23:19.410518Z","end":"2026-07-01T11:23:19.596511Z","steps":["trace[1019755271] 'read index received' (duration: 19.031945ms)","trace[1019755271] 'applied index is now lower than readState.Index' (duration: 166.959367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:23:19.596701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.169697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:23:19.596741Z","caller":"traceutil/trace.go:171","msg":"trace[1718369449] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20274; }","duration":"186.258819ms","start":"2026-07-01T11:23:19.410472Z","end":"2026-07-01T11:23:19.596731Z","steps":["trace[1718369449] 'agreement among raft nodes before linearized reading' (duration: 186.167246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:23:19.596803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.687938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:23:19.596863Z","caller":"traceutil/trace.go:171","msg":"trace[2015324497] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:20274; }","duration":"180.801521ms","start":"2026-07-01T11:23:19.416046Z","end":"2026-07-01T11:23:19.596847Z","steps":["trace[2015324497] 'agreement among raft nodes before linearized reading' (duration: 180.689497ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:23:19.596582Z","caller":"traceutil/trace.go:171","msg":"trace[1060737735] transaction","detail":"{read_only:false; response_revision:20274; number_of_response:1; }","duration":"244.93406ms","start":"2026-07-01T11:23:19.351619Z","end":"2026-07-01T11:23:19.596553Z","steps":["trace[1060737735] 'process raft request' (duration: 244.716044ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:23:20.340939Z","caller":"traceutil/trace.go:171","msg":"trace[602866981] linearizableReadLoop","detail":"{readStateIndex:20961; appliedIndex:20960; }","duration":"168.745019ms","start":"2026-07-01T11:23:20.172169Z","end":"2026-07-01T11:23:20.340914Z","steps":["trace[602866981] 'read index received' (duration: 93.171696ms)","trace[602866981] 'applied index is now lower than readState.Index' (duration: 75.572323ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:23:20.341103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.905143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:23:20.341171Z","caller":"traceutil/trace.go:171","msg":"trace[117258253] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:20276; }","duration":"169.004257ms","start":"2026-07-01T11:23:20.172138Z","end":"2026-07-01T11:23:20.341142Z","steps":["trace[117258253] 'agreement among raft nodes before linearized reading' (duration: 168.867853ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:23:20.341325Z","caller":"traceutil/trace.go:171","msg":"trace[666265190] transaction","detail":"{read_only:false; response_revision:20276; number_of_response:1; }","duration":"183.724345ms","start":"2026-07-01T11:23:20.157561Z","end":"2026-07-01T11:23:20.341285Z","steps":["trace[666265190] 'process raft request' (duration: 107.7324ms)","trace[666265190] 'compare' (duration: 75.48052ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:23:20.341344Z","caller":"traceutil/trace.go:171","msg":"trace[1826689677] transaction","detail":"{read_only:false; response_revision:20277; number_of_response:1; }","duration":"142.775692ms","start":"2026-07-01T11:23:20.198545Z","end":"2026-07-01T11:23:20.341321Z","steps":["trace[1826689677] 'process raft request' (duration: 142.652908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:23:20.632634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.169092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T11:23:20.632712Z","caller":"traceutil/trace.go:171","msg":"trace[1653138350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20279; }","duration":"167.284536ms","start":"2026-07-01T11:23:20.46541Z","end":"2026-07-01T11:23:20.632695Z","steps":["trace[1653138350] 'range keys from in-memory index tree' (duration: 166.946536ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:23:20.826673Z","caller":"traceutil/trace.go:171","msg":"trace[1804415586] transaction","detail":"{read_only:false; response_revision:20280; number_of_response:1; }","duration":"189.840234ms","start":"2026-07-01T11:23:20.63681Z","end":"2026-07-01T11:23:20.82665Z","steps":["trace[1804415586] 'process raft request' (duration: 189.667548ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:27:15.53582Z","caller":"traceutil/trace.go:171","msg":"trace[1827201270] transaction","detail":"{read_only:false; response_revision:22724; number_of_response:1; }","duration":"281.160324ms","start":"2026-07-01T11:27:15.254644Z","end":"2026-07-01T11:27:15.535804Z","steps":["trace[1827201270] 'process raft request' (duration: 260.76971ms)","trace[1827201270] 'compare' (duration: 19.181768ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:28:04.233104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20194} {"level":"info","ts":"2026-07-01T11:28:04.34773Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20194,"took":"110.746824ms","hash":577654101,"current-db-size-bytes":59482112,"current-db-size":"60 MB","current-db-size-in-use-bytes":42725376,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-01T11:28:04.347805Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":577654101,"revision":20194,"compact-revision":17848} {"level":"info","ts":"2026-07-01T11:30:47.164201Z","caller":"traceutil/trace.go:171","msg":"trace[70997135] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"140.792849ms","start":"2026-07-01T11:30:47.023371Z","end":"2026-07-01T11:30:47.164164Z","steps":["trace[70997135] 'process raft request' (duration: 87.194389ms)","trace[70997135] 'compare' (duration: 53.412514ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:47.234112Z","caller":"traceutil/trace.go:171","msg":"trace[487028124] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"101.249628ms","start":"2026-07-01T11:30:47.132825Z","end":"2026-07-01T11:30:47.234074Z","steps":["trace[487028124] 'process raft request' (duration: 101.068052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:48.157674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.790691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:30:48.157928Z","caller":"traceutil/trace.go:171","msg":"trace[1788181237] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"219.720665ms","start":"2026-07-01T11:30:47.938174Z","end":"2026-07-01T11:30:48.157895Z","steps":["trace[1788181237] 'process raft request' (duration: 219.674074ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:48.157969Z","caller":"traceutil/trace.go:171","msg":"trace[1716731589] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"220.421035ms","start":"2026-07-01T11:30:47.937515Z","end":"2026-07-01T11:30:48.157936Z","steps":["trace[1716731589] 'process raft request' (duration: 220.270131ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:48.158006Z","caller":"traceutil/trace.go:171","msg":"trace[1070582617] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"331.938522ms","start":"2026-07-01T11:30:47.82604Z","end":"2026-07-01T11:30:48.157978Z","steps":["trace[1070582617] 'process raft request' (duration: 70.757019ms)","trace[1070582617] 'compare' (duration: 260.648387ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:48.158175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:47.82602Z","time spent":"332.057075ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:30:48.434374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.769385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:30:48.434458Z","caller":"traceutil/trace.go:171","msg":"trace[976635582] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"189.62772ms","start":"2026-07-01T11:30:48.244816Z","end":"2026-07-01T11:30:48.434444Z","steps":["trace[976635582] 'process raft request' (duration: 68.722961ms)","trace[976635582] 'compare' (duration: 120.674693ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:50.88243Z","caller":"traceutil/trace.go:171","msg":"trace[615361990] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"282.987667ms","start":"2026-07-01T11:30:50.599418Z","end":"2026-07-01T11:30:50.882405Z","steps":["trace[615361990] 'process raft request' (duration: 202.206896ms)","trace[615361990] 'compare' (duration: 80.39884ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:50.882623Z","caller":"traceutil/trace.go:171","msg":"trace[1426387494] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"235.830614ms","start":"2026-07-01T11:30:50.646787Z","end":"2026-07-01T11:30:50.882618Z","steps":["trace[1426387494] 'process raft request' (duration: 235.595307ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:50.88272Z","caller":"traceutil/trace.go:171","msg":"trace[995912528] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"282.762601ms","start":"2026-07-01T11:30:50.599953Z","end":"2026-07-01T11:30:50.882716Z","steps":["trace[995912528] 'process raft request' (duration: 282.36812ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:50.882787Z","caller":"traceutil/trace.go:171","msg":"trace[1039546315] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"141.848979ms","start":"2026-07-01T11:30:50.740931Z","end":"2026-07-01T11:30:50.88278Z","steps":["trace[1039546315] 'process raft request' (duration: 141.497489ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:50.882821Z","caller":"traceutil/trace.go:171","msg":"trace[831310888] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25715; }","duration":"232.737535ms","start":"2026-07-01T11:30:50.650078Z","end":"2026-07-01T11:30:50.882816Z","steps":["trace[831310888] 'read index received' (duration: 151.525361ms)","trace[831310888] 'applied index is now lower than readState.Index' (duration: 81.211474ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:50.882865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.774146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:30:50.882883Z","caller":"traceutil/trace.go:171","msg":"trace[1070801538] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24902; }","duration":"232.822227ms","start":"2026-07-01T11:30:50.650053Z","end":"2026-07-01T11:30:50.882875Z","steps":["trace[1070801538] 'agreement among raft nodes before linearized reading' (duration: 232.777725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:51.1442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.196755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:30:51.144304Z","caller":"traceutil/trace.go:171","msg":"trace[1332932513] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24902; }","duration":"146.312328ms","start":"2026-07-01T11:30:50.997973Z","end":"2026-07-01T11:30:51.144285Z","steps":["trace[1332932513] 'range keys from in-memory index tree' (duration: 145.98562ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:51.144972Z","caller":"traceutil/trace.go:171","msg":"trace[1065072580] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"130.096497ms","start":"2026-07-01T11:30:51.014841Z","end":"2026-07-01T11:30:51.144938Z","steps":["trace[1065072580] 'process raft request' (duration: 56.859225ms)","trace[1065072580] 'compare' (duration: 73.096918ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:51.602196Z","caller":"traceutil/trace.go:171","msg":"trace[715642677] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"418.75175ms","start":"2026-07-01T11:30:51.183416Z","end":"2026-07-01T11:30:51.602167Z","steps":["trace[715642677] 'process raft request' (duration: 418.564605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:51.602395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:51.183399Z","time spent":"418.867243ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:30:51.80961Z","caller":"traceutil/trace.go:171","msg":"trace[387956359] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25721; }","duration":"558.075774ms","start":"2026-07-01T11:30:51.251508Z","end":"2026-07-01T11:30:51.809584Z","steps":["trace[387956359] 'read index received' (duration: 350.469612ms)","trace[387956359] 'applied index is now lower than readState.Index' (duration: 207.604582ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:51.809824Z","caller":"traceutil/trace.go:171","msg":"trace[193327388] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"562.913495ms","start":"2026-07-01T11:30:51.246876Z","end":"2026-07-01T11:30:51.809789Z","steps":["trace[193327388] 'process raft request' (duration: 489.378845ms)","trace[193327388] 'compare' (duration: 73.039086ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:51.809989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.466885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:30:51.81008Z","caller":"traceutil/trace.go:171","msg":"trace[75446830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24905; }","duration":"558.567918ms","start":"2026-07-01T11:30:51.251496Z","end":"2026-07-01T11:30:51.810064Z","steps":["trace[75446830] 'agreement among raft nodes before linearized reading' (duration: 558.367272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:51.810072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.657146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:30:51.810176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.989791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T11:30:51.810189Z","caller":"traceutil/trace.go:171","msg":"trace[663560251] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24905; }","duration":"508.834211ms","start":"2026-07-01T11:30:51.301326Z","end":"2026-07-01T11:30:51.81016Z","steps":["trace[663560251] 'agreement among raft nodes before linearized reading' (duration: 508.631015ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:51.810215Z","caller":"traceutil/trace.go:171","msg":"trace[540546405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24905; }","duration":"166.068663ms","start":"2026-07-01T11:30:51.644137Z","end":"2026-07-01T11:30:51.810205Z","steps":["trace[540546405] 'agreement among raft nodes before linearized reading' (duration: 165.937499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:51.810192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:51.251454Z","time spent":"558.688942ms","remote":"127.0.0.1:47478","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-01T11:30:51.810004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:51.246834Z","time spent":"563.076749ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:30:51.810255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:51.301304Z","time spent":"508.933424ms","remote":"127.0.0.1:58386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"info","ts":"2026-07-01T11:30:51.950481Z","caller":"traceutil/trace.go:171","msg":"trace[1656580308] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"118.828869ms","start":"2026-07-01T11:30:51.831629Z","end":"2026-07-01T11:30:51.950457Z","steps":["trace[1656580308] 'process raft request' (duration: 118.726856ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:51.950502Z","caller":"traceutil/trace.go:171","msg":"trace[1763244803] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"135.670219ms","start":"2026-07-01T11:30:51.814805Z","end":"2026-07-01T11:30:51.950475Z","steps":["trace[1763244803] 'process raft request' (duration: 133.535537ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:52.407585Z","caller":"traceutil/trace.go:171","msg":"trace[372933279] linearizableReadLoop","detail":"{readStateIndex:25727; appliedIndex:25725; }","duration":"223.118383ms","start":"2026-07-01T11:30:52.184433Z","end":"2026-07-01T11:30:52.407551Z","steps":["trace[372933279] 'read index received' (duration: 60.345736ms)","trace[372933279] 'applied index is now lower than readState.Index' (duration: 162.771317ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:52.407778Z","caller":"traceutil/trace.go:171","msg":"trace[1264455452] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"233.070774ms","start":"2026-07-01T11:30:52.174671Z","end":"2026-07-01T11:30:52.407742Z","steps":["trace[1264455452] 'process raft request' (duration: 232.711183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:52.407899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.452903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-01T11:30:52.407956Z","caller":"traceutil/trace.go:171","msg":"trace[1621700621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24909; }","duration":"223.565606ms","start":"2026-07-01T11:30:52.184371Z","end":"2026-07-01T11:30:52.407936Z","steps":["trace[1621700621] 'agreement among raft nodes before linearized reading' (duration: 223.312959ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:52.588076Z","caller":"traceutil/trace.go:171","msg":"trace[258779592] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"133.212969ms","start":"2026-07-01T11:30:52.454797Z","end":"2026-07-01T11:30:52.58801Z","steps":["trace[258779592] 'process raft request' (duration: 130.904401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:53.069941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.841759ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-01T11:30:53.070228Z","caller":"traceutil/trace.go:171","msg":"trace[1278625074] linearizableReadLoop","detail":"{readStateIndex:25731; appliedIndex:25730; }","duration":"183.288916ms","start":"2026-07-01T11:30:52.886914Z","end":"2026-07-01T11:30:53.070203Z","steps":["trace[1278625074] 'read index received' (duration: 42.267391ms)","trace[1278625074] 'applied index is now lower than readState.Index' (duration: 141.020045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:53.070537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.609485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-01T11:30:53.070708Z","caller":"traceutil/trace.go:171","msg":"trace[1502491221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24912; }","duration":"183.78106ms","start":"2026-07-01T11:30:52.886894Z","end":"2026-07-01T11:30:53.070675Z","steps":["trace[1502491221] 'agreement among raft nodes before linearized reading' (duration: 183.413279ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:53.070737Z","caller":"traceutil/trace.go:171","msg":"trace[1170439550] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"182.606995ms","start":"2026-07-01T11:30:52.888077Z","end":"2026-07-01T11:30:53.070684Z","steps":["trace[1170439550] 'process raft request' (duration: 181.997917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:53.070781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.699788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T11:30:53.070912Z","caller":"traceutil/trace.go:171","msg":"trace[1277033535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24913; }","duration":"182.901123ms","start":"2026-07-01T11:30:52.887973Z","end":"2026-07-01T11:30:53.070874Z","steps":["trace[1277033535] 'agreement among raft nodes before linearized reading' (duration: 182.509852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:53.070911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.105239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:30:53.071034Z","caller":"traceutil/trace.go:171","msg":"trace[2016278207] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24913; }","duration":"116.316245ms","start":"2026-07-01T11:30:52.954698Z","end":"2026-07-01T11:30:53.071014Z","steps":["trace[2016278207] 'agreement among raft nodes before linearized reading' (duration: 116.038607ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:53.375772Z","caller":"traceutil/trace.go:171","msg":"trace[1509318088] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"300.848926ms","start":"2026-07-01T11:30:53.074889Z","end":"2026-07-01T11:30:53.375738Z","steps":["trace[1509318088] 'process raft request' (duration: 271.987617ms)","trace[1509318088] 'compare' (duration: 28.18134ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:53.375957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:53.074865Z","time spent":"300.993571ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:30:53.376356Z","caller":"traceutil/trace.go:171","msg":"trace[139269299] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"300.754364ms","start":"2026-07-01T11:30:53.075577Z","end":"2026-07-01T11:30:53.376332Z","steps":["trace[139269299] 'process raft request' (duration: 300.050364ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:53.376437Z","caller":"traceutil/trace.go:171","msg":"trace[39543504] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"225.479613ms","start":"2026-07-01T11:30:53.15094Z","end":"2026-07-01T11:30:53.376419Z","steps":["trace[39543504] 'process raft request' (duration: 225.203115ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:53.376429Z","caller":"traceutil/trace.go:171","msg":"trace[828718636] linearizableReadLoop","detail":"{readStateIndex:25734; appliedIndex:25732; }","duration":"279.857406ms","start":"2026-07-01T11:30:53.096526Z","end":"2026-07-01T11:30:53.376384Z","steps":["trace[828718636] 'read index received' (duration: 250.341057ms)","trace[828718636] 'applied index is now lower than readState.Index' (duration: 29.515049ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:53.376501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:53.075565Z","time spent":"300.856667ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:30:53.376713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.61153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:30:53.376777Z","caller":"traceutil/trace.go:171","msg":"trace[1058975603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24916; }","duration":"132.732453ms","start":"2026-07-01T11:30:53.244027Z","end":"2026-07-01T11:30:53.37676Z","steps":["trace[1058975603] 'agreement among raft nodes before linearized reading' (duration: 132.572258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:53.376891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.34865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:30:53.377004Z","caller":"traceutil/trace.go:171","msg":"trace[25757771] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24916; }","duration":"280.516105ms","start":"2026-07-01T11:30:53.09647Z","end":"2026-07-01T11:30:53.376986Z","steps":["trace[25757771] 'agreement among raft nodes before linearized reading' (duration: 280.099023ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:53.948638Z","caller":"traceutil/trace.go:171","msg":"trace[687564532] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"204.564605ms","start":"2026-07-01T11:30:53.744043Z","end":"2026-07-01T11:30:53.948608Z","steps":["trace[687564532] 'process raft request' (duration: 204.345589ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:53.958316Z","caller":"traceutil/trace.go:171","msg":"trace[2070645874] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"142.411575ms","start":"2026-07-01T11:30:53.815886Z","end":"2026-07-01T11:30:53.958298Z","steps":["trace[2070645874] 'process raft request' (duration: 142.260231ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:54.554713Z","caller":"traceutil/trace.go:171","msg":"trace[956360012] linearizableReadLoop","detail":"{readStateIndex:25743; appliedIndex:25742; }","duration":"129.215021ms","start":"2026-07-01T11:30:54.425476Z","end":"2026-07-01T11:30:54.554691Z","steps":["trace[956360012] 'read index received' (duration: 128.969744ms)","trace[956360012] 'applied index is now lower than readState.Index' (duration: 244.437µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:54.554914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.418367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-01T11:30:54.554945Z","caller":"traceutil/trace.go:171","msg":"trace[1578374647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24924; }","duration":"129.492689ms","start":"2026-07-01T11:30:54.425443Z","end":"2026-07-01T11:30:54.554935Z","steps":["trace[1578374647] 'agreement among raft nodes before linearized reading' (duration: 129.338375ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:54.555364Z","caller":"traceutil/trace.go:171","msg":"trace[1506946789] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"132.183608ms","start":"2026-07-01T11:30:54.423143Z","end":"2026-07-01T11:30:54.555327Z","steps":["trace[1506946789] 'process raft request' (duration: 131.384265ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:54.753243Z","caller":"traceutil/trace.go:171","msg":"trace[1484377652] linearizableReadLoop","detail":"{readStateIndex:25744; appliedIndex:25743; }","duration":"161.331356ms","start":"2026-07-01T11:30:54.591887Z","end":"2026-07-01T11:30:54.753218Z","steps":["trace[1484377652] 'read index received' (duration: 146.36678ms)","trace[1484377652] 'applied index is now lower than readState.Index' (duration: 14.963126ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:54.753272Z","caller":"traceutil/trace.go:171","msg":"trace[1217393226] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"194.278506ms","start":"2026-07-01T11:30:54.558962Z","end":"2026-07-01T11:30:54.75324Z","steps":["trace[1217393226] 'process raft request' (duration: 179.370161ms)","trace[1217393226] 'compare' (duration: 14.695889ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:54.753442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.536372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-01T11:30:54.753484Z","caller":"traceutil/trace.go:171","msg":"trace[1138231101] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24925; }","duration":"161.622444ms","start":"2026-07-01T11:30:54.591847Z","end":"2026-07-01T11:30:54.753469Z","steps":["trace[1138231101] 'agreement among raft nodes before linearized reading' (duration: 161.46052ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:54.758272Z","caller":"traceutil/trace.go:171","msg":"trace[930389788] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"164.528268ms","start":"2026-07-01T11:30:54.593707Z","end":"2026-07-01T11:30:54.758236Z","steps":["trace[930389788] 'process raft request' (duration: 164.25119ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:54.924363Z","caller":"traceutil/trace.go:171","msg":"trace[1470018851] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"163.210151ms","start":"2026-07-01T11:30:54.760953Z","end":"2026-07-01T11:30:54.924163Z","steps":["trace[1470018851] 'process raft request' (duration: 149.922204ms)","trace[1470018851] 'compare' (duration: 12.884275ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:55.233399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.609079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-07-01T11:30:55.233483Z","caller":"traceutil/trace.go:171","msg":"trace[116851152] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24928; }","duration":"166.724472ms","start":"2026-07-01T11:30:55.066736Z","end":"2026-07-01T11:30:55.233461Z","steps":["trace[116851152] 'range keys from in-memory index tree' (duration: 166.241669ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:55.233663Z","caller":"traceutil/trace.go:171","msg":"trace[919981020] linearizableReadLoop","detail":"{readStateIndex:25748; appliedIndex:25747; }","duration":"102.644777ms","start":"2026-07-01T11:30:55.13099Z","end":"2026-07-01T11:30:55.233635Z","steps":["trace[919981020] 'read index received' (duration: 13.706038ms)","trace[919981020] 'applied index is now lower than readState.Index' (duration: 88.937099ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:55.233902Z","caller":"traceutil/trace.go:171","msg":"trace[545946933] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"156.177635ms","start":"2026-07-01T11:30:55.077703Z","end":"2026-07-01T11:30:55.233881Z","steps":["trace[545946933] 'process raft request' (duration: 66.975789ms)","trace[545946933] 'compare' (duration: 88.498466ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:55.233952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.960376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:30:55.233994Z","caller":"traceutil/trace.go:171","msg":"trace[2078423556] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24929; }","duration":"103.037498ms","start":"2026-07-01T11:30:55.130944Z","end":"2026-07-01T11:30:55.233982Z","steps":["trace[2078423556] 'agreement among raft nodes before linearized reading' (duration: 102.934125ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:55.415798Z","caller":"traceutil/trace.go:171","msg":"trace[1224115030] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"129.058816ms","start":"2026-07-01T11:30:55.286717Z","end":"2026-07-01T11:30:55.415776Z","steps":["trace[1224115030] 'process raft request' (duration: 125.466302ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:55.669832Z","caller":"traceutil/trace.go:171","msg":"trace[1933877885] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"242.86807ms","start":"2026-07-01T11:30:55.426943Z","end":"2026-07-01T11:30:55.669811Z","steps":["trace[1933877885] 'process raft request' (duration: 242.839249ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:55.669885Z","caller":"traceutil/trace.go:171","msg":"trace[1374352390] linearizableReadLoop","detail":"{readStateIndex:25753; appliedIndex:25752; }","duration":"253.926052ms","start":"2026-07-01T11:30:55.41591Z","end":"2026-07-01T11:30:55.669836Z","steps":["trace[1374352390] 'read index received' (duration: 209.424156ms)","trace[1374352390] 'applied index is now lower than readState.Index' (duration: 44.495145ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:55.669961Z","caller":"traceutil/trace.go:171","msg":"trace[25005451] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"245.297121ms","start":"2026-07-01T11:30:55.424658Z","end":"2026-07-01T11:30:55.669955Z","steps":["trace[25005451] 'process raft request' (duration: 245.089215ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:55.67005Z","caller":"traceutil/trace.go:171","msg":"trace[144718739] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"256.068093ms","start":"2026-07-01T11:30:55.413965Z","end":"2026-07-01T11:30:55.670033Z","steps":["trace[144718739] 'process raft request' (duration: 211.393233ms)","trace[144718739] 'compare' (duration: 44.257608ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:55.670411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.994298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18be270bc14c6cab\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-07-01T11:30:55.670491Z","caller":"traceutil/trace.go:171","msg":"trace[462642225] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18be270bc14c6cab; range_end:; response_count:1; response_revision:24936; }","duration":"248.107612ms","start":"2026-07-01T11:30:55.422363Z","end":"2026-07-01T11:30:55.670471Z","steps":["trace[462642225] 'agreement among raft nodes before linearized reading' (duration: 247.873575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:55.958528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.660343ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:30:55.958681Z","caller":"traceutil/trace.go:171","msg":"trace[623855681] linearizableReadLoop","detail":"{readStateIndex:25756; appliedIndex:25755; }","duration":"276.751815ms","start":"2026-07-01T11:30:55.681906Z","end":"2026-07-01T11:30:55.958658Z","steps":["trace[623855681] 'read index received' (duration: 122.828015ms)","trace[623855681] 'applied index is now lower than readState.Index' (duration: 153.92199ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:55.958732Z","caller":"traceutil/trace.go:171","msg":"trace[441432998] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"283.934954ms","start":"2026-07-01T11:30:55.674761Z","end":"2026-07-01T11:30:55.958696Z","steps":["trace[441432998] 'process raft request' (duration: 129.976163ms)","trace[441432998] 'compare' (duration: 153.490768ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:55.958871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.968102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-01T11:30:55.958923Z","caller":"traceutil/trace.go:171","msg":"trace[622892335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24937; }","duration":"277.049875ms","start":"2026-07-01T11:30:55.681861Z","end":"2026-07-01T11:30:55.958911Z","steps":["trace[622892335] 'agreement among raft nodes before linearized reading' (duration: 276.854889ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:56.155516Z","caller":"traceutil/trace.go:171","msg":"trace[677897897] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"189.879907ms","start":"2026-07-01T11:30:55.965622Z","end":"2026-07-01T11:30:56.155502Z","steps":["trace[677897897] 'process raft request' (duration: 189.328471ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:56.155511Z","caller":"traceutil/trace.go:171","msg":"trace[818686541] linearizableReadLoop","detail":"{readStateIndex:25759; appliedIndex:25757; }","duration":"157.785362ms","start":"2026-07-01T11:30:55.997704Z","end":"2026-07-01T11:30:56.15549Z","steps":["trace[818686541] 'read index received' (duration: 157.211976ms)","trace[818686541] 'applied index is now lower than readState.Index' (duration: 572.256µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:56.155771Z","caller":"traceutil/trace.go:171","msg":"trace[1240703964] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"173.050687ms","start":"2026-07-01T11:30:55.982661Z","end":"2026-07-01T11:30:56.155712Z","steps":["trace[1240703964] 'process raft request' (duration: 172.780759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:56.155698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.975458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T11:30:56.156436Z","caller":"traceutil/trace.go:171","msg":"trace[1813675623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24940; }","duration":"158.757411ms","start":"2026-07-01T11:30:55.997665Z","end":"2026-07-01T11:30:56.156422Z","steps":["trace[1813675623] 'agreement among raft nodes before linearized reading' (duration: 157.886825ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:56.450866Z","caller":"traceutil/trace.go:171","msg":"trace[2039534844] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"186.639683ms","start":"2026-07-01T11:30:56.264205Z","end":"2026-07-01T11:30:56.450845Z","steps":["trace[2039534844] 'process raft request' (duration: 178.059104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:57.175981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.246219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:30:57.17614Z","caller":"traceutil/trace.go:171","msg":"trace[780481576] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"413.242209ms","start":"2026-07-01T11:30:56.76288Z","end":"2026-07-01T11:30:57.176122Z","steps":["trace[780481576] 'process raft request' (duration: 280.781772ms)","trace[780481576] 'compare' (duration: 132.106065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:57.176199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:56.762859Z","time spent":"413.312031ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:30:57.176357Z","caller":"traceutil/trace.go:171","msg":"trace[1964651113] linearizableReadLoop","detail":"{readStateIndex:25765; appliedIndex:25763; }","duration":"159.327528ms","start":"2026-07-01T11:30:57.016999Z","end":"2026-07-01T11:30:57.176327Z","steps":["trace[1964651113] 'read index received' (duration: 26.735788ms)","trace[1964651113] 'applied index is now lower than readState.Index' (duration: 132.59071ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:57.176688Z","caller":"traceutil/trace.go:171","msg":"trace[480115373] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"353.059696ms","start":"2026-07-01T11:30:56.823612Z","end":"2026-07-01T11:30:57.176671Z","steps":["trace[480115373] 'process raft request' (duration: 352.465179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:57.176807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:30:56.823586Z","time spent":"353.139109ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:30:57.176951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.943185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-07-01T11:30:57.177031Z","caller":"traceutil/trace.go:171","msg":"trace[492388666] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24946; }","duration":"160.08229ms","start":"2026-07-01T11:30:57.016931Z","end":"2026-07-01T11:30:57.177013Z","steps":["trace[492388666] 'agreement among raft nodes before linearized reading' (duration: 159.865184ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:57.356325Z","caller":"traceutil/trace.go:171","msg":"trace[711835227] linearizableReadLoop","detail":"{readStateIndex:25766; appliedIndex:25765; }","duration":"115.365928ms","start":"2026-07-01T11:30:57.240938Z","end":"2026-07-01T11:30:57.356304Z","steps":["trace[711835227] 'read index received' (duration: 99.919998ms)","trace[711835227] 'applied index is now lower than readState.Index' (duration: 15.44543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:57.35645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.496281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:30:57.356474Z","caller":"traceutil/trace.go:171","msg":"trace[776512279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24947; }","duration":"115.566003ms","start":"2026-07-01T11:30:57.240899Z","end":"2026-07-01T11:30:57.356465Z","steps":["trace[776512279] 'agreement among raft nodes before linearized reading' (duration: 115.496541ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:57.356734Z","caller":"traceutil/trace.go:171","msg":"trace[295102471] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"118.013775ms","start":"2026-07-01T11:30:57.238675Z","end":"2026-07-01T11:30:57.356688Z","steps":["trace[295102471] 'process raft request' (duration: 102.272227ms)","trace[295102471] 'compare' (duration: 15.271384ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:57.632959Z","caller":"traceutil/trace.go:171","msg":"trace[1068948672] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"206.592274ms","start":"2026-07-01T11:30:57.426342Z","end":"2026-07-01T11:30:57.632934Z","steps":["trace[1068948672] 'process raft request' (duration: 206.366328ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:30:57.885041Z","caller":"traceutil/trace.go:171","msg":"trace[455851683] linearizableReadLoop","detail":"{readStateIndex:25769; appliedIndex:25768; }","duration":"210.198629ms","start":"2026-07-01T11:30:57.674817Z","end":"2026-07-01T11:30:57.885016Z","steps":["trace[455851683] 'read index received' (duration: 111.087703ms)","trace[455851683] 'applied index is now lower than readState.Index' (duration: 99.110346ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:30:57.885152Z","caller":"traceutil/trace.go:171","msg":"trace[910684705] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"253.714195ms","start":"2026-07-01T11:30:57.63139Z","end":"2026-07-01T11:30:57.885105Z","steps":["trace[910684705] 'process raft request' (duration: 154.594589ms)","trace[910684705] 'compare' (duration: 98.940491ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:30:57.885246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.431565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-01T11:30:57.885285Z","caller":"traceutil/trace.go:171","msg":"trace[228911219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24949; }","duration":"210.523558ms","start":"2026-07-01T11:30:57.674743Z","end":"2026-07-01T11:30:57.885267Z","steps":["trace[228911219] 'agreement among raft nodes before linearized reading' (duration: 210.382244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:30:57.885476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.183028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-01T11:30:57.885575Z","caller":"traceutil/trace.go:171","msg":"trace[395567253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24949; }","duration":"210.310922ms","start":"2026-07-01T11:30:57.675241Z","end":"2026-07-01T11:30:57.885552Z","steps":["trace[395567253] 'agreement among raft nodes before linearized reading' (duration: 210.044775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:00.787874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.083091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:31:00.787972Z","caller":"traceutil/trace.go:171","msg":"trace[2043437556] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24971; }","duration":"170.225936ms","start":"2026-07-01T11:31:00.617731Z","end":"2026-07-01T11:31:00.787957Z","steps":["trace[2043437556] 'count revisions from in-memory index tree' (duration: 170.006029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:00.788018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.27879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:31:00.788133Z","caller":"traceutil/trace.go:171","msg":"trace[1878993933] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24971; }","duration":"188.439625ms","start":"2026-07-01T11:31:00.599655Z","end":"2026-07-01T11:31:00.788095Z","steps":["trace[1878993933] 'count revisions from in-memory index tree' (duration: 188.172637ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:01.184171Z","caller":"traceutil/trace.go:171","msg":"trace[1679298294] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"539.937955ms","start":"2026-07-01T11:31:00.644216Z","end":"2026-07-01T11:31:01.184154Z","steps":["trace[1679298294] 'process raft request' (duration: 539.819562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:01.184282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:00.64419Z","time spent":"540.035148ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:31:01.384724Z","caller":"traceutil/trace.go:171","msg":"trace[2028389268] linearizableReadLoop","detail":"{readStateIndex:25793; appliedIndex:25791; }","duration":"513.726044ms","start":"2026-07-01T11:31:00.870975Z","end":"2026-07-01T11:31:01.384701Z","steps":["trace[2028389268] 'read index received' (duration: 313.145254ms)","trace[2028389268] 'applied index is now lower than readState.Index' (duration: 200.57962ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:01.38478Z","caller":"traceutil/trace.go:171","msg":"trace[2095640438] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"549.818554ms","start":"2026-07-01T11:31:00.834899Z","end":"2026-07-01T11:31:01.384717Z","steps":["trace[2095640438] 'process raft request' (duration: 537.960209ms)","trace[2095640438] 'compare' (duration: 11.392052ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:01.38481Z","caller":"traceutil/trace.go:171","msg":"trace[2116016356] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"479.752965ms","start":"2026-07-01T11:31:00.905033Z","end":"2026-07-01T11:31:01.384786Z","steps":["trace[2116016356] 'process raft request' (duration: 479.602541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:01.384877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.877588ms","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:8"} {"level":"info","ts":"2026-07-01T11:31:01.384913Z","caller":"traceutil/trace.go:171","msg":"trace[832581575] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24974; }","duration":"513.971651ms","start":"2026-07-01T11:31:00.870929Z","end":"2026-07-01T11:31:01.384901Z","steps":["trace[832581575] 'agreement among raft nodes before linearized reading' (duration: 513.893029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:01.384932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:00.904997Z","time spent":"479.871298ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T11:31:01.384945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:00.870909Z","time spent":"514.026622ms","remote":"127.0.0.1:59984","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-01T11:31:01.385002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:00.834881Z","time spent":"550.001489ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:31:01.430341Z","caller":"traceutil/trace.go:171","msg":"trace[1544792970] transaction","detail":"{read_only:false; response_revision:24975; number_of_response:1; }","duration":"239.077589ms","start":"2026-07-01T11:31:01.191237Z","end":"2026-07-01T11:31:01.430315Z","steps":["trace[1544792970] 'process raft request' (duration: 238.890954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:01.430601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.524671ms","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:8"} {"level":"warn","ts":"2026-07-01T11:31:01.430611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.022593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:31:01.430648Z","caller":"traceutil/trace.go:171","msg":"trace[1458662805] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24976; }","duration":"117.627504ms","start":"2026-07-01T11:31:01.313007Z","end":"2026-07-01T11:31:01.430634Z","steps":["trace[1458662805] 'agreement among raft nodes before linearized reading' (duration: 117.508811ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:01.430674Z","caller":"traceutil/trace.go:171","msg":"trace[55906723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24976; }","duration":"187.116117ms","start":"2026-07-01T11:31:01.243542Z","end":"2026-07-01T11:31:01.430658Z","steps":["trace[55906723] 'agreement among raft nodes before linearized reading' (duration: 187.024244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:01.430701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.447579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-01T11:31:01.430746Z","caller":"traceutil/trace.go:171","msg":"trace[309101548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24976; }","duration":"240.49245ms","start":"2026-07-01T11:31:01.190243Z","end":"2026-07-01T11:31:01.430736Z","steps":["trace[309101548] 'agreement among raft nodes before linearized reading' (duration: 240.347536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:01.430861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.795617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:31:01.430899Z","caller":"traceutil/trace.go:171","msg":"trace[277133579] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24976; }","duration":"255.174117ms","start":"2026-07-01T11:31:01.175712Z","end":"2026-07-01T11:31:01.430886Z","steps":["trace[277133579] 'agreement among raft nodes before linearized reading' (duration: 254.776176ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:09.96364Z","caller":"traceutil/trace.go:171","msg":"trace[1361093087] linearizableReadLoop","detail":"{readStateIndex:25917; appliedIndex:25916; }","duration":"263.988315ms","start":"2026-07-01T11:31:09.699605Z","end":"2026-07-01T11:31:09.963593Z","steps":["trace[1361093087] 'read index received' (duration: 192.504594ms)","trace[1361093087] 'applied index is now lower than readState.Index' (duration: 71.48171ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:09.963723Z","caller":"traceutil/trace.go:171","msg":"trace[1364987137] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"293.04747ms","start":"2026-07-01T11:31:09.67063Z","end":"2026-07-01T11:31:09.963677Z","steps":["trace[1364987137] 'process raft request' (duration: 221.535469ms)","trace[1364987137] 'compare' (duration: 71.018657ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:31:09.963922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.290053ms","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:8"} {"level":"warn","ts":"2026-07-01T11:31:09.963973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.592338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-657ee049-aab4-4ac4-b3aa-a2bb9fad9845\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-01T11:31:09.964005Z","caller":"traceutil/trace.go:171","msg":"trace[2036410026] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25094; }","duration":"264.415957ms","start":"2026-07-01T11:31:09.699565Z","end":"2026-07-01T11:31:09.963981Z","steps":["trace[2036410026] 'agreement among raft nodes before linearized reading' (duration: 264.19703ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:09.964027Z","caller":"traceutil/trace.go:171","msg":"trace[1210468076] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-657ee049-aab4-4ac4-b3aa-a2bb9fad9845; range_end:; response_count:1; response_revision:25094; }","duration":"257.703111ms","start":"2026-07-01T11:31:09.706305Z","end":"2026-07-01T11:31:09.964008Z","steps":["trace[1210468076] 'agreement among raft nodes before linearized reading' (duration: 257.478295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:09.964424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.932354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:31:09.964467Z","caller":"traceutil/trace.go:171","msg":"trace[1182060330] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25094; }","duration":"128.007236ms","start":"2026-07-01T11:31:09.836449Z","end":"2026-07-01T11:31:09.964457Z","steps":["trace[1182060330] 'agreement among raft nodes before linearized reading' (duration: 127.905693ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:10.400256Z","caller":"traceutil/trace.go:171","msg":"trace[375417019] linearizableReadLoop","detail":"{readStateIndex:25922; appliedIndex:25921; }","duration":"171.643736ms","start":"2026-07-01T11:31:10.228577Z","end":"2026-07-01T11:31:10.400221Z","steps":["trace[375417019] 'read index received' (duration: 171.271935ms)","trace[375417019] 'applied index is now lower than readState.Index' (duration: 370.021µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:10.400355Z","caller":"traceutil/trace.go:171","msg":"trace[2074604992] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"205.079149ms","start":"2026-07-01T11:31:10.195236Z","end":"2026-07-01T11:31:10.400315Z","steps":["trace[2074604992] 'process raft request' (duration: 204.7489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:10.400594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.885517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T11:31:10.400673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.680036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-01T11:31:10.400687Z","caller":"traceutil/trace.go:171","msg":"trace[64117198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25099; }","duration":"157.068902ms","start":"2026-07-01T11:31:10.243598Z","end":"2026-07-01T11:31:10.400667Z","steps":["trace[64117198] 'agreement among raft nodes before linearized reading' (duration: 156.863166ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:10.400724Z","caller":"traceutil/trace.go:171","msg":"trace[132144774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25099; }","duration":"171.796171ms","start":"2026-07-01T11:31:10.228912Z","end":"2026-07-01T11:31:10.400708Z","steps":["trace[132144774] 'agreement among raft nodes before linearized reading' (duration: 171.494662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:10.400684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.082938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T11:31:10.400787Z","caller":"traceutil/trace.go:171","msg":"trace[1821668602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25099; }","duration":"172.271584ms","start":"2026-07-01T11:31:10.228497Z","end":"2026-07-01T11:31:10.400769Z","steps":["trace[1821668602] 'agreement among raft nodes before linearized reading' (duration: 171.887772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:10.649513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.131655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:31:10.649658Z","caller":"traceutil/trace.go:171","msg":"trace[1721592604] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"244.386744ms","start":"2026-07-01T11:31:10.40524Z","end":"2026-07-01T11:31:10.649627Z","steps":["trace[1721592604] 'process raft request' (duration: 99.024972ms)","trace[1721592604] 'compare' (duration: 144.636671ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:10.650934Z","caller":"traceutil/trace.go:171","msg":"trace[463344523] linearizableReadLoop","detail":"{readStateIndex:25924; appliedIndex:25922; }","duration":"207.730707ms","start":"2026-07-01T11:31:10.44318Z","end":"2026-07-01T11:31:10.650911Z","steps":["trace[463344523] 'read index received' (duration: 61.227512ms)","trace[463344523] 'applied index is now lower than readState.Index' (duration: 146.502175ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:10.650977Z","caller":"traceutil/trace.go:171","msg":"trace[1363018171] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"245.690753ms","start":"2026-07-01T11:31:10.405256Z","end":"2026-07-01T11:31:10.650946Z","steps":["trace[1363018171] 'process raft request' (duration: 245.464896ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:10.651006Z","caller":"traceutil/trace.go:171","msg":"trace[396463642] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"157.812355ms","start":"2026-07-01T11:31:10.493172Z","end":"2026-07-01T11:31:10.650985Z","steps":["trace[396463642] 'process raft request' (duration: 157.686501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:10.651196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.016586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-657ee049-aab4-4ac4-b3aa-a2bb9fad9845\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-01T11:31:10.651271Z","caller":"traceutil/trace.go:171","msg":"trace[795450585] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-657ee049-aab4-4ac4-b3aa-a2bb9fad9845; range_end:; response_count:1; response_revision:25102; }","duration":"208.149379ms","start":"2026-07-01T11:31:10.443099Z","end":"2026-07-01T11:31:10.651249Z","steps":["trace[795450585] 'agreement among raft nodes before linearized reading' (duration: 207.908402ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:10.812262Z","caller":"traceutil/trace.go:171","msg":"trace[1998018119] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"153.492118ms","start":"2026-07-01T11:31:10.658747Z","end":"2026-07-01T11:31:10.812239Z","steps":["trace[1998018119] 'process raft request' (duration: 153.344793ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:12.842554Z","caller":"traceutil/trace.go:171","msg":"trace[1303285779] transaction","detail":"{read_only:false; response_revision:25119; number_of_response:1; }","duration":"184.510271ms","start":"2026-07-01T11:31:12.658016Z","end":"2026-07-01T11:31:12.842526Z","steps":["trace[1303285779] 'process raft request' (duration: 184.454969ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:12.842688Z","caller":"traceutil/trace.go:171","msg":"trace[685778834] transaction","detail":"{read_only:false; response_revision:25118; number_of_response:1; }","duration":"185.234561ms","start":"2026-07-01T11:31:12.657403Z","end":"2026-07-01T11:31:12.842637Z","steps":["trace[685778834] 'process raft request' (duration: 154.729473ms)","trace[685778834] 'compare' (duration: 30.187779ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:16.860721Z","caller":"traceutil/trace.go:171","msg":"trace[759181598] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"123.577567ms","start":"2026-07-01T11:31:16.737109Z","end":"2026-07-01T11:31:16.860686Z","steps":["trace[759181598] 'process raft request' (duration: 57.399011ms)","trace[759181598] 'compare' (duration: 65.96175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:31:17.240825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.380539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:31:17.240879Z","caller":"traceutil/trace.go:171","msg":"trace[735787687] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25171; }","duration":"171.481062ms","start":"2026-07-01T11:31:17.069385Z","end":"2026-07-01T11:31:17.240866Z","steps":["trace[735787687] 'count revisions from in-memory index tree' (duration: 171.290325ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:17.491058Z","caller":"traceutil/trace.go:171","msg":"trace[1333714661] transaction","detail":"{read_only:false; response_revision:25172; number_of_response:1; }","duration":"254.222619ms","start":"2026-07-01T11:31:17.236811Z","end":"2026-07-01T11:31:17.491033Z","steps":["trace[1333714661] 'process raft request' (duration: 254.073445ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:17.491178Z","caller":"traceutil/trace.go:171","msg":"trace[2135247369] linearizableReadLoop","detail":"{readStateIndex:25997; appliedIndex:25997; }","duration":"248.608906ms","start":"2026-07-01T11:31:17.242542Z","end":"2026-07-01T11:31:17.491151Z","steps":["trace[2135247369] 'read index received' (duration: 248.586015ms)","trace[2135247369] 'applied index is now lower than readState.Index' (duration: 20.771µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:31:17.49137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.809301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:31:17.491408Z","caller":"traceutil/trace.go:171","msg":"trace[332186960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25172; }","duration":"248.880994ms","start":"2026-07-01T11:31:17.242513Z","end":"2026-07-01T11:31:17.491394Z","steps":["trace[332186960] 'agreement among raft nodes before linearized reading' (duration: 248.788911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:17.56267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.488853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-07-01T11:31:17.562751Z","caller":"traceutil/trace.go:171","msg":"trace[674836017] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:25175; }","duration":"203.603047ms","start":"2026-07-01T11:31:17.35913Z","end":"2026-07-01T11:31:17.562733Z","steps":["trace[674836017] 'agreement among raft nodes before linearized reading' (duration: 203.410731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:17.562767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.624816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-07-01T11:31:17.562842Z","caller":"traceutil/trace.go:171","msg":"trace[1603474079] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:25175; }","duration":"306.740099ms","start":"2026-07-01T11:31:17.256081Z","end":"2026-07-01T11:31:17.562821Z","steps":["trace[1603474079] 'agreement among raft nodes before linearized reading' (duration: 306.486522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:17.562881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:17.256068Z","time spent":"306.804941ms","remote":"127.0.0.1:47652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-07-01T11:31:17.563073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.168996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-01T11:31:17.563054Z","caller":"traceutil/trace.go:171","msg":"trace[287458455] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"120.396425ms","start":"2026-07-01T11:31:17.442622Z","end":"2026-07-01T11:31:17.563018Z","steps":["trace[287458455] 'process raft request' (duration: 117.274004ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:17.563121Z","caller":"traceutil/trace.go:171","msg":"trace[827822859] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:25175; }","duration":"144.241248ms","start":"2026-07-01T11:31:17.418867Z","end":"2026-07-01T11:31:17.563108Z","steps":["trace[827822859] 'agreement among raft nodes before linearized reading' (duration: 144.091634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:21.78566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.82818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:31:21.785782Z","caller":"traceutil/trace.go:171","msg":"trace[1685218496] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25206; }","duration":"240.013156ms","start":"2026-07-01T11:31:21.545738Z","end":"2026-07-01T11:31:21.785751Z","steps":["trace[1685218496] 'range keys from in-memory index tree' (duration: 239.697957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:21.785861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.743725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:31:21.785938Z","caller":"traceutil/trace.go:171","msg":"trace[1935610542] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:25206; }","duration":"231.877409ms","start":"2026-07-01T11:31:21.554043Z","end":"2026-07-01T11:31:21.785921Z","steps":["trace[1935610542] 'count revisions from in-memory index tree' (duration: 231.664162ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:21.786011Z","caller":"traceutil/trace.go:171","msg":"trace[903172326] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"209.104125ms","start":"2026-07-01T11:31:21.576878Z","end":"2026-07-01T11:31:21.785983Z","steps":["trace[903172326] 'process raft request' (duration: 209.001362ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:21.786231Z","caller":"traceutil/trace.go:171","msg":"trace[1845685250] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"209.369114ms","start":"2026-07-01T11:31:21.576852Z","end":"2026-07-01T11:31:21.786221Z","steps":["trace[1845685250] 'process raft request' (duration: 152.132078ms)","trace[1845685250] 'compare' (duration: 56.670489ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:21.996398Z","caller":"traceutil/trace.go:171","msg":"trace[918670624] linearizableReadLoop","detail":"{readStateIndex:26035; appliedIndex:26032; }","duration":"415.370241ms","start":"2026-07-01T11:31:21.581006Z","end":"2026-07-01T11:31:21.996376Z","steps":["trace[918670624] 'read index received' (duration: 148.159603ms)","trace[918670624] 'applied index is now lower than readState.Index' (duration: 267.209638ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:21.99646Z","caller":"traceutil/trace.go:171","msg":"trace[1201028039] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"418.851392ms","start":"2026-07-01T11:31:21.577567Z","end":"2026-07-01T11:31:21.996418Z","steps":["trace[1201028039] 'process raft request' (duration: 418.658426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:21.996587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.562567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-01T11:31:21.996615Z","caller":"traceutil/trace.go:171","msg":"trace[510648935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25209; }","duration":"415.646639ms","start":"2026-07-01T11:31:21.580961Z","end":"2026-07-01T11:31:21.996608Z","steps":["trace[510648935] 'agreement among raft nodes before linearized reading' (duration: 415.497415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:21.996671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:21.580937Z","time spent":"415.700571ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T11:31:21.996719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:21.577543Z","time spent":"419.033987ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:31:22.080416Z","caller":"traceutil/trace.go:171","msg":"trace[1027780879] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"345.745224ms","start":"2026-07-01T11:31:21.73463Z","end":"2026-07-01T11:31:22.080375Z","steps":["trace[1027780879] 'process raft request' (duration: 327.094081ms)","trace[1027780879] 'compare' (duration: 18.401905ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:31:22.080596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T11:31:21.73458Z","time spent":"345.91253ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T11:31:26.824717Z","caller":"traceutil/trace.go:171","msg":"trace[1677975034] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"165.649032ms","start":"2026-07-01T11:31:26.658963Z","end":"2026-07-01T11:31:26.824612Z","steps":["trace[1677975034] 'process raft request' (duration: 165.180319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:41.29435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.23064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:31:41.294683Z","caller":"traceutil/trace.go:171","msg":"trace[1034179555] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"257.836405ms","start":"2026-07-01T11:31:41.036804Z","end":"2026-07-01T11:31:41.29464Z","steps":["trace[1034179555] 'process raft request' (duration: 116.250174ms)","trace[1034179555] 'compare' (duration: 141.063256ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:41.294731Z","caller":"traceutil/trace.go:171","msg":"trace[1128507874] transaction","detail":"{read_only:false; response_revision:25370; number_of_response:1; }","duration":"256.699951ms","start":"2026-07-01T11:31:41.037987Z","end":"2026-07-01T11:31:41.294687Z","steps":["trace[1128507874] 'process raft request' (duration: 256.462264ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:42.265388Z","caller":"traceutil/trace.go:171","msg":"trace[870785355] linearizableReadLoop","detail":"{readStateIndex:26205; appliedIndex:26204; }","duration":"107.216311ms","start":"2026-07-01T11:31:42.158153Z","end":"2026-07-01T11:31:42.265369Z","steps":["trace[870785355] 'read index received' (duration: 107.20014ms)","trace[870785355] 'applied index is now lower than readState.Index' (duration: 15.381µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:42.265407Z","caller":"traceutil/trace.go:171","msg":"trace[1032192534] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"116.719087ms","start":"2026-07-01T11:31:42.148665Z","end":"2026-07-01T11:31:42.265384Z","steps":["trace[1032192534] 'process raft request' (duration: 116.597724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:42.265558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.396616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-01T11:31:42.265582Z","caller":"traceutil/trace.go:171","msg":"trace[1321499534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25375; }","duration":"107.469578ms","start":"2026-07-01T11:31:42.158105Z","end":"2026-07-01T11:31:42.265574Z","steps":["trace[1321499534] 'agreement among raft nodes before linearized reading' (duration: 107.357655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:42.301876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.844262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-01T11:31:42.301946Z","caller":"traceutil/trace.go:171","msg":"trace[1497172584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25376; }","duration":"137.934145ms","start":"2026-07-01T11:31:42.163995Z","end":"2026-07-01T11:31:42.301929Z","steps":["trace[1497172584] 'agreement among raft nodes before linearized reading' (duration: 137.75755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:42.302104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.846715ms","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-01T11:31:42.302191Z","caller":"traceutil/trace.go:171","msg":"trace[1278919802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25376; }","duration":"123.958928ms","start":"2026-07-01T11:31:42.178215Z","end":"2026-07-01T11:31:42.302174Z","steps":["trace[1278919802] 'agreement among raft nodes before linearized reading' (duration: 123.799764ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:42.397753Z","caller":"traceutil/trace.go:171","msg":"trace[982205368] transaction","detail":"{read_only:false; response_revision:25378; number_of_response:1; }","duration":"127.708596ms","start":"2026-07-01T11:31:42.270018Z","end":"2026-07-01T11:31:42.397727Z","steps":["trace[982205368] 'process raft request' (duration: 127.559032ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:42.39776Z","caller":"traceutil/trace.go:171","msg":"trace[2059979577] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"128.12717ms","start":"2026-07-01T11:31:42.269583Z","end":"2026-07-01T11:31:42.39771Z","steps":["trace[2059979577] 'process raft request' (duration: 101.412593ms)","trace[2059979577] 'compare' (duration: 26.454719ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:42.426519Z","caller":"traceutil/trace.go:171","msg":"trace[757870097] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"117.799069ms","start":"2026-07-01T11:31:42.308701Z","end":"2026-07-01T11:31:42.4265Z","steps":["trace[757870097] 'process raft request' (duration: 117.754367ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:42.426605Z","caller":"traceutil/trace.go:171","msg":"trace[4698182] transaction","detail":"{read_only:false; response_revision:25379; number_of_response:1; }","duration":"117.870031ms","start":"2026-07-01T11:31:42.308699Z","end":"2026-07-01T11:31:42.426569Z","steps":["trace[4698182] 'process raft request' (duration: 117.665435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:43.190015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.659917ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-01T11:31:43.190181Z","caller":"traceutil/trace.go:171","msg":"trace[560621008] linearizableReadLoop","detail":"{readStateIndex:26212; appliedIndex:26211; }","duration":"173.787439ms","start":"2026-07-01T11:31:43.016381Z","end":"2026-07-01T11:31:43.190168Z","steps":["trace[560621008] 'read index received' (duration: 38.531µs)","trace[560621008] 'applied index is now lower than readState.Index' (duration: 173.747888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:31:43.190234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.85021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:31:43.190255Z","caller":"traceutil/trace.go:171","msg":"trace[1266025603] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25381; }","duration":"173.929733ms","start":"2026-07-01T11:31:43.016317Z","end":"2026-07-01T11:31:43.190247Z","steps":["trace[1266025603] 'agreement among raft nodes before linearized reading' (duration: 173.883172ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:43.190494Z","caller":"traceutil/trace.go:171","msg":"trace[2102901137] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"158.478832ms","start":"2026-07-01T11:31:43.031972Z","end":"2026-07-01T11:31:43.190451Z","steps":["trace[2102901137] 'process raft request' (duration: 158.127112ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:43.549801Z","caller":"traceutil/trace.go:171","msg":"trace[2053857121] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"109.891018ms","start":"2026-07-01T11:31:43.439871Z","end":"2026-07-01T11:31:43.549762Z","steps":["trace[2053857121] 'process raft request' (duration: 90.6735ms)","trace[2053857121] 'compare' (duration: 18.993842ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:43.806679Z","caller":"traceutil/trace.go:171","msg":"trace[1005909668] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"144.104614ms","start":"2026-07-01T11:31:43.662556Z","end":"2026-07-01T11:31:43.80666Z","steps":["trace[1005909668] 'process raft request' (duration: 143.94874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:31:44.490623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.574272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T11:31:44.490766Z","caller":"traceutil/trace.go:171","msg":"trace[618505862] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:25392; }","duration":"106.775477ms","start":"2026-07-01T11:31:44.38397Z","end":"2026-07-01T11:31:44.490745Z","steps":["trace[618505862] 'count revisions from in-memory index tree' (duration: 106.47372ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:44.610648Z","caller":"traceutil/trace.go:171","msg":"trace[2031771192] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"115.43725ms","start":"2026-07-01T11:31:44.49519Z","end":"2026-07-01T11:31:44.610627Z","steps":["trace[2031771192] 'process raft request' (duration: 102.431071ms)","trace[2031771192] 'compare' (duration: 12.854475ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:31:44.610841Z","caller":"traceutil/trace.go:171","msg":"trace[2082435164] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"115.574194ms","start":"2026-07-01T11:31:44.49526Z","end":"2026-07-01T11:31:44.610834Z","steps":["trace[2082435164] 'process raft request' (duration: 115.335257ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:31:55.638234Z","caller":"traceutil/trace.go:171","msg":"trace[548831922] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"211.569139ms","start":"2026-07-01T11:31:55.426635Z","end":"2026-07-01T11:31:55.638204Z","steps":["trace[548831922] 'process raft request' (duration: 135.38361ms)","trace[548831922] 'compare' (duration: 76.063394ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:32:06.178514Z","caller":"traceutil/trace.go:171","msg":"trace[1171639492] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"104.375668ms","start":"2026-07-01T11:32:06.074114Z","end":"2026-07-01T11:32:06.17849Z","steps":["trace[1171639492] 'process raft request' (duration: 104.0943ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:32:06.540648Z","caller":"traceutil/trace.go:171","msg":"trace[1708055516] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"117.788689ms","start":"2026-07-01T11:32:06.422819Z","end":"2026-07-01T11:32:06.540608Z","steps":["trace[1708055516] 'process raft request' (duration: 47.430941ms)","trace[1708055516] 'compare' (duration: 70.156842ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:32:06.59018Z","caller":"traceutil/trace.go:171","msg":"trace[1981841834] transaction","detail":"{read_only:false; response_revision:25561; number_of_response:1; }","duration":"164.495508ms","start":"2026-07-01T11:32:06.425658Z","end":"2026-07-01T11:32:06.590153Z","steps":["trace[1981841834] 'process raft request' (duration: 164.323643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:32:06.745762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.352698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T11:32:06.74591Z","caller":"traceutil/trace.go:171","msg":"trace[1144143121] transaction","detail":"{read_only:false; response_revision:25562; number_of_response:1; }","duration":"126.05093ms","start":"2026-07-01T11:32:06.619831Z","end":"2026-07-01T11:32:06.745882Z","steps":["trace[1144143121] 'process raft request' (duration: 22.489295ms)","trace[1144143121] 'compare' (duration: 103.226014ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:32:06.7464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.127774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:32:06.746461Z","caller":"traceutil/trace.go:171","msg":"trace[1665463433] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25563; }","duration":"101.268928ms","start":"2026-07-01T11:32:06.645179Z","end":"2026-07-01T11:32:06.746448Z","steps":["trace[1665463433] 'agreement among raft nodes before linearized reading' (duration: 101.129674ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:32:06.746246Z","caller":"traceutil/trace.go:171","msg":"trace[411751264] linearizableReadLoop","detail":"{readStateIndex:26397; appliedIndex:26396; }","duration":"100.929498ms","start":"2026-07-01T11:32:06.645235Z","end":"2026-07-01T11:32:06.746165Z","steps":["trace[411751264] 'read index received' (duration: 58.971µs)","trace[411751264] 'applied index is now lower than readState.Index' (duration: 100.868507ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:32:06.863017Z","caller":"traceutil/trace.go:171","msg":"trace[9599847] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"111.765563ms","start":"2026-07-01T11:32:06.751219Z","end":"2026-07-01T11:32:06.862985Z","steps":["trace[9599847] 'process raft request' (duration: 98.93648ms)","trace[9599847] 'compare' (duration: 12.674469ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:32:27.330062Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006819.wal"} {"level":"info","ts":"2026-07-01T11:33:04.239563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23191} {"level":"info","ts":"2026-07-01T11:33:04.371673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23191,"took":"127.522383ms","hash":354183312,"current-db-size-bytes":59904000,"current-db-size":"60 MB","current-db-size-in-use-bytes":35659776,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-01T11:33:04.371745Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":354183312,"revision":23191,"compact-revision":20194} {"level":"info","ts":"2026-07-01T11:33:50.16299Z","caller":"traceutil/trace.go:171","msg":"trace[606517909] linearizableReadLoop","detail":"{readStateIndex:27453; appliedIndex:27452; }","duration":"101.395861ms","start":"2026-07-01T11:33:50.061566Z","end":"2026-07-01T11:33:50.162962Z","steps":["trace[606517909] 'read index received' (duration: 101.171244ms)","trace[606517909] 'applied index is now lower than readState.Index' (duration: 223.437µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:50.163098Z","caller":"traceutil/trace.go:171","msg":"trace[377434535] transaction","detail":"{read_only:false; response_revision:26593; number_of_response:1; }","duration":"191.775333ms","start":"2026-07-01T11:33:49.971288Z","end":"2026-07-01T11:33:50.163064Z","steps":["trace[377434535] 'process raft request' (duration: 191.507205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:50.163274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.68352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:50.16334Z","caller":"traceutil/trace.go:171","msg":"trace[1041360746] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26593; }","duration":"101.796613ms","start":"2026-07-01T11:33:50.061535Z","end":"2026-07-01T11:33:50.163332Z","steps":["trace[1041360746] 'agreement among raft nodes before linearized reading' (duration: 101.629738ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:50.436704Z","caller":"traceutil/trace.go:171","msg":"trace[934044898] linearizableReadLoop","detail":"{readStateIndex:27454; appliedIndex:27453; }","duration":"190.698701ms","start":"2026-07-01T11:33:50.245979Z","end":"2026-07-01T11:33:50.436677Z","steps":["trace[934044898] 'read index received' (duration: 190.497175ms)","trace[934044898] 'applied index is now lower than readState.Index' (duration: 200.206µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:33:50.436794Z","caller":"traceutil/trace.go:171","msg":"trace[322586680] transaction","detail":"{read_only:false; response_revision:26594; number_of_response:1; }","duration":"268.787554ms","start":"2026-07-01T11:33:50.167978Z","end":"2026-07-01T11:33:50.436766Z","steps":["trace[322586680] 'process raft request' (duration: 268.526106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T11:33:50.436927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.918797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T11:33:50.43702Z","caller":"traceutil/trace.go:171","msg":"trace[1193766731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26594; }","duration":"191.05453ms","start":"2026-07-01T11:33:50.245945Z","end":"2026-07-01T11:33:50.437Z","steps":["trace[1193766731] 'agreement among raft nodes before linearized reading' (duration: 190.877215ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:33:50.635856Z","caller":"traceutil/trace.go:171","msg":"trace[466220949] transaction","detail":"{read_only:false; response_revision:26596; number_of_response:1; }","duration":"173.940113ms","start":"2026-07-01T11:33:50.461874Z","end":"2026-07-01T11:33:50.635814Z","steps":["trace[466220949] 'process raft request' (duration: 84.795248ms)","trace[466220949] 'compare' (duration: 89.002471ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T11:38:04.251216Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26158} {"level":"info","ts":"2026-07-01T11:38:04.393416Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26158,"took":"137.255619ms","hash":171942837,"current-db-size-bytes":59904000,"current-db-size":"60 MB","current-db-size-in-use-bytes":34971648,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-01T11:38:04.393487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":171942837,"revision":26158,"compact-revision":23191} {"level":"info","ts":"2026-07-01T11:38:33.996902Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T11:38:34.001602Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-01T11:38:34.001736Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-01T11:43:04.25628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28855} {"level":"info","ts":"2026-07-01T11:43:04.394777Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28855,"took":"135.178039ms","hash":3583958097,"current-db-size-bytes":59904000,"current-db-size":"60 MB","current-db-size-in-use-bytes":30617600,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T11:43:04.394847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3583958097,"revision":28855,"compact-revision":26158} {"level":"info","ts":"2026-07-01T11:48:04.262503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31074} {"level":"info","ts":"2026-07-01T11:48:04.387659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31074,"took":"121.053998ms","hash":1669495403,"current-db-size-bytes":59904000,"current-db-size":"60 MB","current-db-size-in-use-bytes":30683136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T11:48:04.387738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1669495403,"revision":31074,"compact-revision":28855} {"level":"info","ts":"2026-07-01T11:50:12.159738Z","caller":"traceutil/trace.go:171","msg":"trace[1637894660] linearizableReadLoop","detail":"{readStateIndex:35447; appliedIndex:35445; }","duration":"119.291937ms","start":"2026-07-01T11:50:12.040424Z","end":"2026-07-01T11:50:12.159716Z","steps":["trace[1637894660] 'read index received' (duration: 9.923519ms)","trace[1637894660] 'applied index is now lower than readState.Index' (duration: 109.367348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T11:50:12.159868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.42027ms","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:8"} {"level":"info","ts":"2026-07-01T11:50:12.159906Z","caller":"traceutil/trace.go:171","msg":"trace[1256207413] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:34369; }","duration":"119.500213ms","start":"2026-07-01T11:50:12.040393Z","end":"2026-07-01T11:50:12.159893Z","steps":["trace[1256207413] 'agreement among raft nodes before linearized reading' (duration: 119.421831ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:50:12.159859Z","caller":"traceutil/trace.go:171","msg":"trace[1490982054] transaction","detail":"{read_only:false; response_revision:34369; number_of_response:1; }","duration":"123.441466ms","start":"2026-07-01T11:50:12.036369Z","end":"2026-07-01T11:50:12.159811Z","steps":["trace[1490982054] 'process raft request' (duration: 123.200009ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T11:53:04.269466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33342} {"level":"info","ts":"2026-07-01T11:53:04.427077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33342,"took":"153.587155ms","hash":169732404,"current-db-size-bytes":60948480,"current-db-size":"61 MB","current-db-size-in-use-bytes":31993856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T11:53:04.427194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":169732404,"revision":33342,"compact-revision":31074} {"level":"info","ts":"2026-07-01T11:58:04.275672Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35633} {"level":"info","ts":"2026-07-01T11:58:04.419047Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35633,"took":"138.562891ms","hash":226853974,"current-db-size-bytes":63479808,"current-db-size":"64 MB","current-db-size-in-use-bytes":31752192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T11:58:04.419184Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":226853974,"revision":35633,"compact-revision":33342} {"level":"info","ts":"2026-07-01T12:00:00.186586Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a847c2e9c7010db6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T12:00:00.199258Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-01T12:00:00.199379Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-01T12:03:04.280759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37862} {"level":"info","ts":"2026-07-01T12:03:04.393814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37862,"took":"110.395211ms","hash":3276870172,"current-db-size-bytes":63479808,"current-db-size":"64 MB","current-db-size-in-use-bytes":31772672,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T12:03:04.393892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3276870172,"revision":37862,"compact-revision":35633}