{"level":"warn","ts":"2026-07-01T07:28:00.06191Z","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-01T07:28:00.061992Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T07:28:00.06205Z","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-01T07:28:00.062059Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-01T07:28:00.062083Z","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-01T07:28:00.062621Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-07-01T07:28:00.062692Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T07:28:00.066903Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.012253ms"} {"level":"info","ts":"2026-07-01T07:28:00.072925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-07-01T07:28:00.073019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:28:00.073046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-07-01T07:28:00.073057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:28:00.073069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-07-01T07:28:00.073105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-07-01T07:28:00.084331Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:28:00.086628Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:28:00.087985Z","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-01T07:28:00.090354Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:28:00.09163Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:28:00.091735Z","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-01T07:28:00.09188Z","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-01T07:28:00.091901Z","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-01T07:28:00.093861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-07-01T07:28:00.094068Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-07-01T07:28:00.097094Z","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-01T07:28:00.097227Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-01T07:28:00.097298Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-07-01T07:28:00.097567Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:28:00.097633Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:28:00.37382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:28:00.373999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:28:00.374094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-07-01T07:28:00.374168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:28:00.374219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-01T07:28:00.374291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-07-01T07:28:00.374332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-07-01T07:28:00.376402Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:28:00.376555Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:28:00.376617Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:28:00.376441Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:28:00.377018Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:28:00.377159Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T07:28:00.378695Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:28:00.378847Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:28:00.378894Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:28:00.380708Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-07-01T07:28:00.381536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-01T07:28:19.837135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.403051ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-01T07:28:38.149681Z","caller":"traceutil/trace.go:171","msg":"trace[2137358765] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"106.568047ms","start":"2026-07-01T07:28:38.043085Z","end":"2026-07-01T07:28:38.149653Z","steps":["trace[2137358765] 'process raft request' (duration: 102.3699ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:38.150101Z","caller":"traceutil/trace.go:171","msg":"trace[1403715674] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"100.90378ms","start":"2026-07-01T07:28:38.049176Z","end":"2026-07-01T07:28:38.15008Z","steps":["trace[1403715674] 'process raft request' (duration: 100.683185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:44.323907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.749567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:28:44.324046Z","caller":"traceutil/trace.go:171","msg":"trace[1407539517] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"147.732485ms","start":"2026-07-01T07:28:44.176296Z","end":"2026-07-01T07:28:44.324028Z","steps":["trace[1407539517] 'read index received' (duration: 12.265092ms)","trace[1407539517] 'applied index is now lower than readState.Index' (duration: 135.465823ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:44.324094Z","caller":"traceutil/trace.go:171","msg":"trace[1216840469] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"313.788818ms","start":"2026-07-01T07:28:44.010269Z","end":"2026-07-01T07:28:44.324058Z","steps":["trace[1216840469] 'process raft request' (duration: 178.378936ms)","trace[1216840469] 'compare' (duration: 134.579344ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:44.3242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.905449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T07:28:44.324261Z","caller":"traceutil/trace.go:171","msg":"trace[1680701460] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:629; }","duration":"148.005771ms","start":"2026-07-01T07:28:44.176239Z","end":"2026-07-01T07:28:44.324244Z","steps":["trace[1680701460] 'agreement among raft nodes before linearized reading' (duration: 147.906689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:44.32438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:44.010243Z","time spent":"314.002892ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:28:44.786028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.561859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:28:44.786144Z","caller":"traceutil/trace.go:171","msg":"trace[1005048732] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"442.840708ms","start":"2026-07-01T07:28:44.343285Z","end":"2026-07-01T07:28:44.786125Z","steps":["trace[1005048732] 'read index received' (duration: 264.182866ms)","trace[1005048732] 'applied index is now lower than readState.Index' (duration: 178.656502ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:44.786297Z","caller":"traceutil/trace.go:171","msg":"trace[806216692] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"450.502485ms","start":"2026-07-01T07:28:44.335757Z","end":"2026-07-01T07:28:44.78626Z","steps":["trace[806216692] 'process raft request' (duration: 271.618298ms)","trace[806216692] 'compare' (duration: 178.280444ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:44.786462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:44.33573Z","time spent":"450.659389ms","remote":"127.0.0.1:52356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:28:44.78648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.187125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-01T07:28:44.786601Z","caller":"traceutil/trace.go:171","msg":"trace[1436376876] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:631; }","duration":"443.352428ms","start":"2026-07-01T07:28:44.343224Z","end":"2026-07-01T07:28:44.786576Z","steps":["trace[1436376876] 'agreement among raft nodes before linearized reading' (duration: 443.136114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:44.786653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:44.343199Z","time spent":"443.444881ms","remote":"127.0.0.1:52342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-01T07:28:44.793151Z","caller":"traceutil/trace.go:171","msg":"trace[736168368] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"420.481037ms","start":"2026-07-01T07:28:44.372629Z","end":"2026-07-01T07:28:44.79311Z","steps":["trace[736168368] 'process raft request' (duration: 419.878145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:44.793235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.654097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T07:28:44.793279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:44.372604Z","time spent":"420.619779ms","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:28:44.793294Z","caller":"traceutil/trace.go:171","msg":"trace[1449270969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"203.78293ms","start":"2026-07-01T07:28:44.589493Z","end":"2026-07-01T07:28:44.793276Z","steps":["trace[1449270969] 'agreement among raft nodes before linearized reading' (duration: 203.530105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:59.459097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.545555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:59.459243Z","caller":"traceutil/trace.go:171","msg":"trace[1186450617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"236.764779ms","start":"2026-07-01T07:28:59.222449Z","end":"2026-07-01T07:28:59.459214Z","steps":["trace[1186450617] 'range keys from in-memory index tree' (duration: 236.440873ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:12.675267Z","caller":"traceutil/trace.go:171","msg":"trace[1232782375] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"110.276253ms","start":"2026-07-01T07:30:12.56496Z","end":"2026-07-01T07:30:12.675236Z","steps":["trace[1232782375] 'process raft request' (duration: 110.10536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:12.95388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.87341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bab87c20-bf25-49b8-ae44-b12d1722f47b\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-01T07:30:12.953987Z","caller":"traceutil/trace.go:171","msg":"trace[683570401] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bab87c20-bf25-49b8-ae44-b12d1722f47b; range_end:; response_count:1; response_revision:1857; }","duration":"215.028162ms","start":"2026-07-01T07:30:12.738938Z","end":"2026-07-01T07:30:12.953966Z","steps":["trace[683570401] 'range keys from in-memory index tree' (duration: 214.675615ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:12.954257Z","caller":"traceutil/trace.go:171","msg":"trace[1524206135] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"130.917849ms","start":"2026-07-01T07:30:12.823313Z","end":"2026-07-01T07:30:12.954231Z","steps":["trace[1524206135] 'read index received' (duration: 107.290031ms)","trace[1524206135] 'applied index is now lower than readState.Index' (duration: 23.626158ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:12.954498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.174713ms","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-01T07:30:12.954567Z","caller":"traceutil/trace.go:171","msg":"trace[638595239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1858; }","duration":"131.298726ms","start":"2026-07-01T07:30:12.823255Z","end":"2026-07-01T07:30:12.954554Z","steps":["trace[638595239] 'agreement among raft nodes before linearized reading' (duration: 131.104342ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:12.954748Z","caller":"traceutil/trace.go:171","msg":"trace[1579897152] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"214.860649ms","start":"2026-07-01T07:30:12.739449Z","end":"2026-07-01T07:30:12.95431Z","steps":["trace[1579897152] 'process raft request' (duration: 191.12498ms)","trace[1579897152] 'compare' (duration: 23.403622ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:13.14122Z","caller":"traceutil/trace.go:171","msg":"trace[1191524752] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"186.836911ms","start":"2026-07-01T07:30:12.95435Z","end":"2026-07-01T07:30:13.141187Z","steps":["trace[1191524752] 'read index received' (duration: 186.30066ms)","trace[1191524752] 'applied index is now lower than readState.Index' (duration: 534.761µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:13.141281Z","caller":"traceutil/trace.go:171","msg":"trace[637554308] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"192.605541ms","start":"2026-07-01T07:30:12.948638Z","end":"2026-07-01T07:30:13.141244Z","steps":["trace[637554308] 'process raft request' (duration: 192.013238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:13.141656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.19828ms","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-01T07:30:13.141775Z","caller":"traceutil/trace.go:171","msg":"trace[115344596] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"190.356594ms","start":"2026-07-01T07:30:12.951387Z","end":"2026-07-01T07:30:13.141743Z","steps":["trace[115344596] 'agreement among raft nodes before linearized reading' (duration: 189.958236ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:13.148632Z","caller":"traceutil/trace.go:171","msg":"trace[1310131560] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"188.198159ms","start":"2026-07-01T07:30:12.960417Z","end":"2026-07-01T07:30:13.148616Z","steps":["trace[1310131560] 'process raft request' (duration: 188.093607ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:13.148663Z","caller":"traceutil/trace.go:171","msg":"trace[63704143] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"189.560897ms","start":"2026-07-01T07:30:12.95906Z","end":"2026-07-01T07:30:13.148621Z","steps":["trace[63704143] 'process raft request' (duration: 189.331722ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:25.064173Z","caller":"traceutil/trace.go:171","msg":"trace[1963139172] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"241.813855ms","start":"2026-07-01T07:30:24.822331Z","end":"2026-07-01T07:30:25.064145Z","steps":["trace[1963139172] 'process raft request' (duration: 241.696532ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:51.73752Z","caller":"traceutil/trace.go:171","msg":"trace[842841361] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"124.329358ms","start":"2026-07-01T07:30:51.613156Z","end":"2026-07-01T07:30:51.737485Z","steps":["trace[842841361] 'read index received' (duration: 124.010211ms)","trace[842841361] 'applied index is now lower than readState.Index' (duration: 317.577µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:51.737576Z","caller":"traceutil/trace.go:171","msg":"trace[21706443] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"139.052273ms","start":"2026-07-01T07:30:51.598468Z","end":"2026-07-01T07:30:51.737521Z","steps":["trace[21706443] 'process raft request' (duration: 138.809118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:51.737969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.747186ms","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-01T07:30:51.738062Z","caller":"traceutil/trace.go:171","msg":"trace[1681852276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2122; }","duration":"124.93846ms","start":"2026-07-01T07:30:51.613103Z","end":"2026-07-01T07:30:51.738042Z","steps":["trace[1681852276] 'agreement among raft nodes before linearized reading' (duration: 124.510591ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:57.562617Z","caller":"traceutil/trace.go:171","msg":"trace[1126728266] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2229; }","duration":"188.939258ms","start":"2026-07-01T07:30:57.373643Z","end":"2026-07-01T07:30:57.562582Z","steps":["trace[1126728266] 'read index received' (duration: 104.041337ms)","trace[1126728266] 'applied index is now lower than readState.Index' (duration: 84.896781ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:57.562804Z","caller":"traceutil/trace.go:171","msg":"trace[1646829639] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"229.488979ms","start":"2026-07-01T07:30:57.333268Z","end":"2026-07-01T07:30:57.562757Z","steps":["trace[1646829639] 'process raft request' (duration: 144.489916ms)","trace[1646829639] 'compare' (duration: 84.669116ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:57.562971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.761917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-07-01T07:30:57.562977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.306185ms","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-01T07:30:57.563098Z","caller":"traceutil/trace.go:171","msg":"trace[1164556327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2148; }","duration":"189.489369ms","start":"2026-07-01T07:30:57.373594Z","end":"2026-07-01T07:30:57.563083Z","steps":["trace[1164556327] 'agreement among raft nodes before linearized reading' (duration: 189.183673ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:57.563027Z","caller":"traceutil/trace.go:171","msg":"trace[388958478] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2148; }","duration":"179.87478ms","start":"2026-07-01T07:30:57.383134Z","end":"2026-07-01T07:30:57.563009Z","steps":["trace[388958478] 'agreement among raft nodes before linearized reading' (duration: 179.664856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:57.563151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.471987ms","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-01T07:30:57.563226Z","caller":"traceutil/trace.go:171","msg":"trace[1701043628] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"105.59165ms","start":"2026-07-01T07:30:57.45761Z","end":"2026-07-01T07:30:57.563202Z","steps":["trace[1701043628] 'agreement among raft nodes before linearized reading' (duration: 105.382216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:03.909376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.805906ms","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-01T07:31:03.909486Z","caller":"traceutil/trace.go:171","msg":"trace[575842884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2178; }","duration":"270.949139ms","start":"2026-07-01T07:31:03.638513Z","end":"2026-07-01T07:31:03.909462Z","steps":["trace[575842884] 'range keys from in-memory index tree' (duration: 270.580851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:03.909491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.575586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-01T07:31:03.909594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.901059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T07:31:03.909679Z","caller":"traceutil/trace.go:171","msg":"trace[1716385392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2178; }","duration":"273.026751ms","start":"2026-07-01T07:31:03.636633Z","end":"2026-07-01T07:31:03.90966Z","steps":["trace[1716385392] 'range keys from in-memory index tree' (duration: 272.730065ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:03.909537Z","caller":"traceutil/trace.go:171","msg":"trace[1258636526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"290.641728ms","start":"2026-07-01T07:31:03.618885Z","end":"2026-07-01T07:31:03.909526Z","steps":["trace[1258636526] 'range keys from in-memory index tree' (duration: 290.491124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:03.909717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.759904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-01T07:31:03.909781Z","caller":"traceutil/trace.go:171","msg":"trace[1841609416] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2178; }","duration":"270.850356ms","start":"2026-07-01T07:31:03.638913Z","end":"2026-07-01T07:31:03.909764Z","steps":["trace[1841609416] 'range keys from in-memory index tree' (duration: 270.640422ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:04.023401Z","caller":"traceutil/trace.go:171","msg":"trace[711118958] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"181.915523ms","start":"2026-07-01T07:31:03.841449Z","end":"2026-07-01T07:31:04.023364Z","steps":["trace[711118958] 'read index received' (duration: 181.754759ms)","trace[711118958] 'applied index is now lower than readState.Index' (duration: 158.974µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:31:04.023462Z","caller":"traceutil/trace.go:171","msg":"trace[1283968478] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"235.332261ms","start":"2026-07-01T07:31:03.788081Z","end":"2026-07-01T07:31:04.023413Z","steps":["trace[1283968478] 'process raft request' (duration: 235.075585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:04.024029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.538385ms","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-01T07:31:04.024116Z","caller":"traceutil/trace.go:171","msg":"trace[1395826512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2179; }","duration":"182.694948ms","start":"2026-07-01T07:31:03.84138Z","end":"2026-07-01T07:31:04.024075Z","steps":["trace[1395826512] 'agreement among raft nodes before linearized reading' (duration: 182.162497ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:04.064074Z","caller":"traceutil/trace.go:171","msg":"trace[1597172650] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"150.474999ms","start":"2026-07-01T07:31:03.91355Z","end":"2026-07-01T07:31:04.064025Z","steps":["trace[1597172650] 'process raft request' (duration: 150.203073ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:04.064298Z","caller":"traceutil/trace.go:171","msg":"trace[1203918177] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"150.125482ms","start":"2026-07-01T07:31:03.914156Z","end":"2026-07-01T07:31:04.064281Z","steps":["trace[1203918177] 'process raft request' (duration: 149.968229ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:04.064327Z","caller":"traceutil/trace.go:171","msg":"trace[1094521248] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"150.298805ms","start":"2026-07-01T07:31:03.913994Z","end":"2026-07-01T07:31:04.064292Z","steps":["trace[1094521248] 'process raft request' (duration: 149.930678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:04.06443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.113681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:31:04.06449Z","caller":"traceutil/trace.go:171","msg":"trace[10998891] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"149.317067ms","start":"2026-07-01T07:31:03.915154Z","end":"2026-07-01T07:31:04.064471Z","steps":["trace[10998891] 'process raft request' (duration: 149.073771ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:04.064503Z","caller":"traceutil/trace.go:171","msg":"trace[1155002141] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2183; }","duration":"162.246814ms","start":"2026-07-01T07:31:03.902235Z","end":"2026-07-01T07:31:04.064482Z","steps":["trace[1155002141] 'agreement among raft nodes before linearized reading' (duration: 162.10389ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:04.211475Z","caller":"traceutil/trace.go:171","msg":"trace[250473774] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"182.977623ms","start":"2026-07-01T07:31:04.028415Z","end":"2026-07-01T07:31:04.211393Z","steps":["trace[250473774] 'process raft request' (duration: 147.717832ms)","trace[250473774] 'compare' (duration: 34.758371ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:35:16.755116Z","caller":"traceutil/trace.go:171","msg":"trace[1210013491] transaction","detail":"{read_only:false; response_revision:3916; number_of_response:1; }","duration":"121.310897ms","start":"2026-07-01T07:35:16.633776Z","end":"2026-07-01T07:35:16.755087Z","steps":["trace[1210013491] 'process raft request' (duration: 118.670832ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:36:38.954823Z","caller":"traceutil/trace.go:171","msg":"trace[759407480] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"105.431564ms","start":"2026-07-01T07:36:38.849343Z","end":"2026-07-01T07:36:38.954775Z","steps":["trace[759407480] 'process raft request' (duration: 105.187729ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:36:39.116343Z","caller":"traceutil/trace.go:171","msg":"trace[1179952224] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4958; }","duration":"127.129093ms","start":"2026-07-01T07:36:38.989182Z","end":"2026-07-01T07:36:39.116311Z","steps":["trace[1179952224] 'process raft request' (duration: 126.885038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:36:39.116609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.354888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-01T07:36:39.116355Z","caller":"traceutil/trace.go:171","msg":"trace[1354937669] linearizableReadLoop","detail":"{readStateIndex:5148; appliedIndex:5146; }","duration":"127.100053ms","start":"2026-07-01T07:36:38.989224Z","end":"2026-07-01T07:36:39.116324Z","steps":["trace[1354937669] 'read index received' (duration: 91.690139ms)","trace[1354937669] 'applied index is now lower than readState.Index' (duration: 35.408814ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:36:39.116711Z","caller":"traceutil/trace.go:171","msg":"trace[353228587] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4959; }","duration":"127.488502ms","start":"2026-07-01T07:36:38.989199Z","end":"2026-07-01T07:36:39.116688Z","steps":["trace[353228587] 'agreement among raft nodes before linearized reading' (duration: 127.223366ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:36:42.91961Z","caller":"traceutil/trace.go:171","msg":"trace[706997713] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"182.755976ms","start":"2026-07-01T07:36:42.736814Z","end":"2026-07-01T07:36:42.91957Z","steps":["trace[706997713] 'process raft request' (duration: 96.523019ms)","trace[706997713] 'compare' (duration: 85.865459ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:36:42.919579Z","caller":"traceutil/trace.go:171","msg":"trace[712612783] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"179.839846ms","start":"2026-07-01T07:36:42.739694Z","end":"2026-07-01T07:36:42.919534Z","steps":["trace[712612783] 'process raft request' (duration: 179.713223ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:36:43.077406Z","caller":"traceutil/trace.go:171","msg":"trace[1599180621] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"151.960429ms","start":"2026-07-01T07:36:42.925416Z","end":"2026-07-01T07:36:43.077376Z","steps":["trace[1599180621] 'process raft request' (duration: 134.750282ms)","trace[1599180621] 'compare' (duration: 17.082284ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:36:43.439915Z","caller":"traceutil/trace.go:171","msg":"trace[1232532344] linearizableReadLoop","detail":"{readStateIndex:5302; appliedIndex:5301; }","duration":"144.83314ms","start":"2026-07-01T07:36:43.295053Z","end":"2026-07-01T07:36:43.439886Z","steps":["trace[1232532344] 'read index received' (duration: 78.523056ms)","trace[1232532344] 'applied index is now lower than readState.Index' (duration: 66.308574ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:36:43.440067Z","caller":"traceutil/trace.go:171","msg":"trace[1681808033] transaction","detail":"{read_only:false; response_revision:5107; number_of_response:1; }","duration":"147.138278ms","start":"2026-07-01T07:36:43.292907Z","end":"2026-07-01T07:36:43.440045Z","steps":["trace[1681808033] 'process raft request' (duration: 80.724822ms)","trace[1681808033] 'compare' (duration: 65.901136ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:36:43.440218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.145606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-gt9wr-tls\" ","response":"range_response_count:1 size:4431"} {"level":"info","ts":"2026-07-01T07:36:43.440266Z","caller":"traceutil/trace.go:171","msg":"trace[345138541] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-gt9wr-tls; range_end:; response_count:1; response_revision:5107; }","duration":"145.227269ms","start":"2026-07-01T07:36:43.295026Z","end":"2026-07-01T07:36:43.440253Z","steps":["trace[345138541] 'agreement among raft nodes before linearized reading' (duration: 145.027715ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:36:43.444528Z","caller":"traceutil/trace.go:171","msg":"trace[1433465967] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"147.899283ms","start":"2026-07-01T07:36:43.296605Z","end":"2026-07-01T07:36:43.444504Z","steps":["trace[1433465967] 'process raft request' (duration: 147.612216ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:36:43.444735Z","caller":"traceutil/trace.go:171","msg":"trace[294185184] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"107.774383ms","start":"2026-07-01T07:36:43.336937Z","end":"2026-07-01T07:36:43.444712Z","steps":["trace[294185184] 'process raft request' (duration: 107.541219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:36:56.320457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.578006ms","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-01T07:36:56.320526Z","caller":"traceutil/trace.go:171","msg":"trace[2077122820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5371; }","duration":"228.691797ms","start":"2026-07-01T07:36:56.091819Z","end":"2026-07-01T07:36:56.320511Z","steps":["trace[2077122820] 'range keys from in-memory index tree' (duration: 228.179358ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:37:03.637312Z","caller":"traceutil/trace.go:171","msg":"trace[1898578674] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"106.370774ms","start":"2026-07-01T07:37:03.530914Z","end":"2026-07-01T07:37:03.637285Z","steps":["trace[1898578674] 'process raft request' (duration: 37.861984ms)","trace[1898578674] 'compare' (duration: 68.128682ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:37:03.785722Z","caller":"traceutil/trace.go:171","msg":"trace[226098332] linearizableReadLoop","detail":"{readStateIndex:5738; appliedIndex:5736; }","duration":"107.382735ms","start":"2026-07-01T07:37:03.678314Z","end":"2026-07-01T07:37:03.785697Z","steps":["trace[226098332] 'read index received' (duration: 17.168536ms)","trace[226098332] 'applied index is now lower than readState.Index' (duration: 90.213169ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:37:03.785753Z","caller":"traceutil/trace.go:171","msg":"trace[1364640176] transaction","detail":"{read_only:false; response_revision:5531; number_of_response:1; }","duration":"123.599821ms","start":"2026-07-01T07:37:03.662113Z","end":"2026-07-01T07:37:03.785713Z","steps":["trace[1364640176] 'process raft request' (duration: 78.69139ms)","trace[1364640176] 'compare' (duration: 44.762668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:37:03.785958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.61858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-db7144e9-dec5-414c-8b83-086d3ba8bb19\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-01T07:37:03.786005Z","caller":"traceutil/trace.go:171","msg":"trace[431925725] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-db7144e9-dec5-414c-8b83-086d3ba8bb19; range_end:; response_count:1; response_revision:5531; }","duration":"107.703381ms","start":"2026-07-01T07:37:03.678288Z","end":"2026-07-01T07:37:03.785992Z","steps":["trace[431925725] 'agreement among raft nodes before linearized reading' (duration: 107.504117ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:37:04.107432Z","caller":"traceutil/trace.go:171","msg":"trace[1387974059] linearizableReadLoop","detail":"{readStateIndex:5739; appliedIndex:5738; }","duration":"134.340884ms","start":"2026-07-01T07:37:03.973062Z","end":"2026-07-01T07:37:04.107403Z","steps":["trace[1387974059] 'read index received' (duration: 134.031926ms)","trace[1387974059] 'applied index is now lower than readState.Index' (duration: 307.618µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:37:04.107589Z","caller":"traceutil/trace.go:171","msg":"trace[921420153] transaction","detail":"{read_only:false; response_revision:5532; number_of_response:1; }","duration":"151.721254ms","start":"2026-07-01T07:37:03.955852Z","end":"2026-07-01T07:37:04.107573Z","steps":["trace[921420153] 'process raft request' (duration: 151.339005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:37:04.107912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.828953ms","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-01T07:37:04.107971Z","caller":"traceutil/trace.go:171","msg":"trace[1920255972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5532; }","duration":"134.933705ms","start":"2026-07-01T07:37:03.973025Z","end":"2026-07-01T07:37:04.107959Z","steps":["trace[1920255972] 'agreement among raft nodes before linearized reading' (duration: 134.743321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:37:04.107962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.637412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-01T07:37:04.108052Z","caller":"traceutil/trace.go:171","msg":"trace[141505636] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5532; }","duration":"124.787615ms","start":"2026-07-01T07:37:03.98324Z","end":"2026-07-01T07:37:04.108028Z","steps":["trace[141505636] 'agreement among raft nodes before linearized reading' (duration: 124.54132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:37:04.108248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.447238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T07:37:04.108283Z","caller":"traceutil/trace.go:171","msg":"trace[1797297389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5532; }","duration":"123.481088ms","start":"2026-07-01T07:37:03.984792Z","end":"2026-07-01T07:37:04.108273Z","steps":["trace[1797297389] 'agreement among raft nodes before linearized reading' (duration: 123.380926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:37:04.108387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.772885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-01T07:37:04.108469Z","caller":"traceutil/trace.go:171","msg":"trace[564543386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5532; }","duration":"124.889358ms","start":"2026-07-01T07:37:03.983562Z","end":"2026-07-01T07:37:04.108451Z","steps":["trace[564543386] 'agreement among raft nodes before linearized reading' (duration: 124.628453ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:38:01.039993Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-07-01T07:38:01.121747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"80.117926ms","hash":782627656,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T07:38:01.121831Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":782627656,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-07-01T07:43:01.048025Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6083} {"level":"info","ts":"2026-07-01T07:43:01.158795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6083,"took":"108.596945ms","hash":395381544,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":21368832,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-07-01T07:43:01.158875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":395381544,"revision":6083,"compact-revision":3009} {"level":"info","ts":"2026-07-01T07:43:16.486826Z","caller":"traceutil/trace.go:171","msg":"trace[154890347] transaction","detail":"{read_only:false; response_revision:8390; number_of_response:1; }","duration":"223.473615ms","start":"2026-07-01T07:43:16.263321Z","end":"2026-07-01T07:43:16.486795Z","steps":["trace[154890347] 'process raft request' (duration: 223.315682ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:43:28.076948Z","caller":"traceutil/trace.go:171","msg":"trace[2106044911] linearizableReadLoop","detail":"{readStateIndex:8746; appliedIndex:8745; }","duration":"197.881244ms","start":"2026-07-01T07:43:27.879027Z","end":"2026-07-01T07:43:28.076908Z","steps":["trace[2106044911] 'read index received' (duration: 197.556957ms)","trace[2106044911] 'applied index is now lower than readState.Index' (duration: 322.627µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:43:28.077051Z","caller":"traceutil/trace.go:171","msg":"trace[237904685] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"209.839308ms","start":"2026-07-01T07:43:27.86717Z","end":"2026-07-01T07:43:28.077009Z","steps":["trace[237904685] 'process raft request' (duration: 209.521082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:43:28.07714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.063518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:43:28.07719Z","caller":"traceutil/trace.go:171","msg":"trace[26678395] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:8446; }","duration":"198.176801ms","start":"2026-07-01T07:43:27.878995Z","end":"2026-07-01T07:43:28.077172Z","steps":["trace[26678395] 'agreement among raft nodes before linearized reading' (duration: 198.053768ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:43:29.416273Z","caller":"traceutil/trace.go:171","msg":"trace[1509343654] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"244.385673ms","start":"2026-07-01T07:43:29.171855Z","end":"2026-07-01T07:43:29.41624Z","steps":["trace[1509343654] 'process raft request' (duration: 244.187199ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:44:01.655506Z","caller":"traceutil/trace.go:171","msg":"trace[833663584] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"106.192113ms","start":"2026-07-01T07:44:01.549262Z","end":"2026-07-01T07:44:01.655455Z","steps":["trace[833663584] 'process raft request' (duration: 106.03768ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:44:11.772906Z","caller":"traceutil/trace.go:171","msg":"trace[1764529936] linearizableReadLoop","detail":"{readStateIndex:9091; appliedIndex:9090; }","duration":"100.452838ms","start":"2026-07-01T07:44:11.672421Z","end":"2026-07-01T07:44:11.772874Z","steps":["trace[1764529936] 'read index received' (duration: 100.331435ms)","trace[1764529936] 'applied index is now lower than readState.Index' (duration: 119.803µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:44:11.773189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.744953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6b8c88f75d-lz5qq.18be1aa36b4d483a\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-07-01T07:44:11.773235Z","caller":"traceutil/trace.go:171","msg":"trace[1936694756] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6b8c88f75d-lz5qq.18be1aa36b4d483a; range_end:; response_count:1; response_revision:8777; }","duration":"100.867566ms","start":"2026-07-01T07:44:11.672354Z","end":"2026-07-01T07:44:11.773222Z","steps":["trace[1936694756] 'agreement among raft nodes before linearized reading' (duration: 100.643511ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:44:11.77326Z","caller":"traceutil/trace.go:171","msg":"trace[481509459] transaction","detail":"{read_only:false; response_revision:8777; number_of_response:1; }","duration":"109.697595ms","start":"2026-07-01T07:44:11.663342Z","end":"2026-07-01T07:44:11.77304Z","steps":["trace[481509459] 'process raft request' (duration: 109.336838ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:44:35.018214Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002496.wal"} {"level":"info","ts":"2026-07-01T07:45:44.23408Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T07:45:44.239584Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-01T07:45:44.239739Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-01T07:48:01.054275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8164} {"level":"info","ts":"2026-07-01T07:48:01.117051Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8164,"took":"60.321671ms","hash":3238846194,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":29036544,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-01T07:48:01.117121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3238846194,"revision":8164,"compact-revision":6083} {"level":"info","ts":"2026-07-01T07:48:53.256478Z","caller":"traceutil/trace.go:171","msg":"trace[912916020] linearizableReadLoop","detail":"{readStateIndex:11658; appliedIndex:11657; }","duration":"119.328246ms","start":"2026-07-01T07:48:53.137119Z","end":"2026-07-01T07:48:53.256447Z","steps":["trace[912916020] 'read index received' (duration: 119.059859ms)","trace[912916020] 'applied index is now lower than readState.Index' (duration: 266.977µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:48:53.256789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.643422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dgqlj\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-07-01T07:48:53.256884Z","caller":"traceutil/trace.go:171","msg":"trace[1067714027] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dgqlj; range_end:; response_count:1; response_revision:11274; }","duration":"119.786335ms","start":"2026-07-01T07:48:53.137069Z","end":"2026-07-01T07:48:53.256855Z","steps":["trace[1067714027] 'agreement among raft nodes before linearized reading' (duration: 119.519399ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:48:53.256568Z","caller":"traceutil/trace.go:171","msg":"trace[876464190] transaction","detail":"{read_only:false; response_revision:11274; number_of_response:1; }","duration":"164.340173ms","start":"2026-07-01T07:48:53.092187Z","end":"2026-07-01T07:48:53.256527Z","steps":["trace[876464190] 'process raft request' (duration: 164.075527ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:49:12.259362Z","caller":"traceutil/trace.go:171","msg":"trace[116461235] transaction","detail":"{read_only:false; response_revision:11384; number_of_response:1; }","duration":"100.94123ms","start":"2026-07-01T07:49:12.158371Z","end":"2026-07-01T07:49:12.259312Z","steps":["trace[116461235] 'process raft request' (duration: 95.58283ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:53:01.061205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10692} {"level":"info","ts":"2026-07-01T07:53:01.14337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10692,"took":"79.234793ms","hash":2532942148,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":26947584,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-01T07:53:01.143452Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2532942148,"revision":10692,"compact-revision":8164} {"level":"info","ts":"2026-07-01T07:55:24.679415Z","caller":"traceutil/trace.go:171","msg":"trace[459723959] transaction","detail":"{read_only:false; response_revision:13891; number_of_response:1; }","duration":"125.179478ms","start":"2026-07-01T07:55:24.554205Z","end":"2026-07-01T07:55:24.679384Z","steps":["trace[459723959] 'process raft request' (duration: 124.972304ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:55:32.940412Z","caller":"traceutil/trace.go:171","msg":"trace[833509726] linearizableReadLoop","detail":"{readStateIndex:14408; appliedIndex:14407; }","duration":"157.229671ms","start":"2026-07-01T07:55:32.78315Z","end":"2026-07-01T07:55:32.940379Z","steps":["trace[833509726] 'read index received' (duration: 156.997657ms)","trace[833509726] 'applied index is now lower than readState.Index' (duration: 230.304µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:55:32.940458Z","caller":"traceutil/trace.go:171","msg":"trace[248927511] transaction","detail":"{read_only:false; response_revision:13930; number_of_response:1; }","duration":"161.024828ms","start":"2026-07-01T07:55:32.779402Z","end":"2026-07-01T07:55:32.940427Z","steps":["trace[248927511] 'process raft request' (duration: 160.762603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:55:32.940753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.587788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-01T07:55:32.940843Z","caller":"traceutil/trace.go:171","msg":"trace[651839793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13930; }","duration":"157.729431ms","start":"2026-07-01T07:55:32.78309Z","end":"2026-07-01T07:55:32.940819Z","steps":["trace[651839793] 'agreement among raft nodes before linearized reading' (duration: 157.466506ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:55:32.941269Z","caller":"traceutil/trace.go:171","msg":"trace[799272242] transaction","detail":"{read_only:false; response_revision:13931; number_of_response:1; }","duration":"148.442122ms","start":"2026-07-01T07:55:32.792797Z","end":"2026-07-01T07:55:32.941239Z","steps":["trace[799272242] 'process raft request' (duration: 148.260029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:55:32.941312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.282903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dgqlj\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-07-01T07:55:32.94143Z","caller":"traceutil/trace.go:171","msg":"trace[1411385681] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dgqlj; range_end:; response_count:1; response_revision:13931; }","duration":"132.452898ms","start":"2026-07-01T07:55:32.808959Z","end":"2026-07-01T07:55:32.941412Z","steps":["trace[1411385681] 'agreement among raft nodes before linearized reading' (duration: 132.207232ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:56:03.860263Z","caller":"traceutil/trace.go:171","msg":"trace[619860653] linearizableReadLoop","detail":"{readStateIndex:14794; appliedIndex:14793; }","duration":"268.034387ms","start":"2026-07-01T07:56:03.592201Z","end":"2026-07-01T07:56:03.860235Z","steps":["trace[619860653] 'read index received' (duration: 267.773141ms)","trace[619860653] 'applied index is now lower than readState.Index' (duration: 259.886µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:56:03.860317Z","caller":"traceutil/trace.go:171","msg":"trace[1855144351] transaction","detail":"{read_only:false; response_revision:14300; number_of_response:1; }","duration":"318.927263ms","start":"2026-07-01T07:56:03.541345Z","end":"2026-07-01T07:56:03.860272Z","steps":["trace[1855144351] 'process raft request' (duration: 318.667567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:56:03.86048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.267453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-faff3061-7e36-4cfc-8d24-3fa43dd4e303\" ","response":"range_response_count:1 size:3756"} {"level":"warn","ts":"2026-07-01T07:56:03.860516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:56:03.541319Z","time spent":"319.095167ms","remote":"127.0.0.1:52356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3741,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:56:03.860555Z","caller":"traceutil/trace.go:171","msg":"trace[1218179387] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-faff3061-7e36-4cfc-8d24-3fa43dd4e303; range_end:; response_count:1; response_revision:14300; }","duration":"268.400215ms","start":"2026-07-01T07:56:03.592138Z","end":"2026-07-01T07:56:03.860538Z","steps":["trace[1218179387] 'agreement among raft nodes before linearized reading' (duration: 268.221052ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:56:56.8419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.543219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-01T07:56:56.842001Z","caller":"traceutil/trace.go:171","msg":"trace[1222649306] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14936; }","duration":"109.708763ms","start":"2026-07-01T07:56:56.732272Z","end":"2026-07-01T07:56:56.84198Z","steps":["trace[1222649306] 'range keys from in-memory index tree' (duration: 109.352266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:56:56.842194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.823225ms","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-01T07:56:56.842257Z","caller":"traceutil/trace.go:171","msg":"trace[1808097971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14936; }","duration":"111.916717ms","start":"2026-07-01T07:56:56.730324Z","end":"2026-07-01T07:56:56.842241Z","steps":["trace[1808097971] 'range keys from in-memory index tree' (duration: 111.687622ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:57:30.997439Z","caller":"traceutil/trace.go:171","msg":"trace[1377992563] transaction","detail":"{read_only:false; response_revision:15112; number_of_response:1; }","duration":"151.293069ms","start":"2026-07-01T07:57:30.846108Z","end":"2026-07-01T07:57:30.997401Z","steps":["trace[1377992563] 'process raft request' (duration: 102.837123ms)","trace[1377992563] 'compare' (duration: 48.244621ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:58:01.067995Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12857} {"level":"info","ts":"2026-07-01T07:58:01.146827Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12857,"took":"75.65854ms","hash":1629539054,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":30089216,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-01T07:58:01.146888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1629539054,"revision":12857,"compact-revision":10692} {"level":"info","ts":"2026-07-01T08:03:01.074358Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15288} {"level":"info","ts":"2026-07-01T08:03:01.174702Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15288,"took":"96.449718ms","hash":2606226259,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":30994432,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T08:03:01.174779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2606226259,"revision":15288,"compact-revision":12857} {"level":"info","ts":"2026-07-01T08:04:06.220586Z","caller":"traceutil/trace.go:171","msg":"trace[1997426341] linearizableReadLoop","detail":"{readStateIndex:18605; appliedIndex:18604; }","duration":"191.103997ms","start":"2026-07-01T08:04:06.029462Z","end":"2026-07-01T08:04:06.220566Z","steps":["trace[1997426341] 'read index received' (duration: 191.059666ms)","trace[1997426341] 'applied index is now lower than readState.Index' (duration: 43.511µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:04:06.220715Z","caller":"traceutil/trace.go:171","msg":"trace[2110411760] transaction","detail":"{read_only:false; response_revision:17991; number_of_response:1; }","duration":"257.127528ms","start":"2026-07-01T08:04:05.963539Z","end":"2026-07-01T08:04:06.220667Z","steps":["trace[2110411760] 'process raft request' (duration: 256.885774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:04:06.220839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.359183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29714880-d94kd\" ","response":"range_response_count:1 size:7040"} {"level":"info","ts":"2026-07-01T08:04:06.22089Z","caller":"traceutil/trace.go:171","msg":"trace[1537575674] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29714880-d94kd; range_end:; response_count:1; response_revision:17991; }","duration":"191.473835ms","start":"2026-07-01T08:04:06.029405Z","end":"2026-07-01T08:04:06.220879Z","steps":["trace[1537575674] 'agreement among raft nodes before linearized reading' (duration: 191.295501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:04:07.049637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.797837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:04:07.049775Z","caller":"traceutil/trace.go:171","msg":"trace[1705114515] linearizableReadLoop","detail":"{readStateIndex:18607; appliedIndex:18606; }","duration":"164.851646ms","start":"2026-07-01T08:04:06.884904Z","end":"2026-07-01T08:04:07.049756Z","steps":["trace[1705114515] 'read index received' (duration: 56.799844ms)","trace[1705114515] 'applied index is now lower than readState.Index' (duration: 108.049462ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:04:07.049872Z","caller":"traceutil/trace.go:171","msg":"trace[852379635] transaction","detail":"{read_only:false; response_revision:17993; number_of_response:1; }","duration":"224.419663ms","start":"2026-07-01T08:04:06.825417Z","end":"2026-07-01T08:04:07.049837Z","steps":["trace[852379635] 'process raft request' (duration: 116.29846ms)","trace[852379635] 'compare' (duration: 107.572803ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:04:07.050116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.260712ms","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-01T08:04:07.0502Z","caller":"traceutil/trace.go:171","msg":"trace[77826623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17993; }","duration":"162.372015ms","start":"2026-07-01T08:04:06.887808Z","end":"2026-07-01T08:04:07.05018Z","steps":["trace[77826623] 'agreement among raft nodes before linearized reading' (duration: 162.173501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:04:07.050678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.771794ms","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-01T08:04:07.050727Z","caller":"traceutil/trace.go:171","msg":"trace[28911289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17993; }","duration":"165.856026ms","start":"2026-07-01T08:04:06.884858Z","end":"2026-07-01T08:04:07.050714Z","steps":["trace[28911289] 'agreement among raft nodes before linearized reading' (duration: 164.989809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:04:07.050391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.288282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:04:07.050905Z","caller":"traceutil/trace.go:171","msg":"trace[161190037] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:17993; }","duration":"128.831044ms","start":"2026-07-01T08:04:06.92206Z","end":"2026-07-01T08:04:07.050891Z","steps":["trace[161190037] 'agreement among raft nodes before linearized reading' (duration: 127.986227ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:06:35.839869Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T08:06:35.846086Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-01T08:06:35.846194Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-01T08:08:01.079958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17540} {"level":"info","ts":"2026-07-01T08:08:01.181367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17540,"took":"98.021818ms","hash":1358300271,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":33763328,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-01T08:08:01.181428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1358300271,"revision":17540,"compact-revision":15288} {"level":"info","ts":"2026-07-01T08:09:42.206603Z","caller":"traceutil/trace.go:171","msg":"trace[744865675] transaction","detail":"{read_only:false; response_revision:20663; number_of_response:1; }","duration":"121.823424ms","start":"2026-07-01T08:09:42.084749Z","end":"2026-07-01T08:09:42.206573Z","steps":["trace[744865675] 'process raft request' (duration: 121.62673ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:42.565452Z","caller":"traceutil/trace.go:171","msg":"trace[28625353] transaction","detail":"{read_only:false; response_revision:20664; number_of_response:1; }","duration":"137.070454ms","start":"2026-07-01T08:09:42.428333Z","end":"2026-07-01T08:09:42.565403Z","steps":["trace[28625353] 'process raft request' (duration: 136.89968ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:43.130182Z","caller":"traceutil/trace.go:171","msg":"trace[1418436083] linearizableReadLoop","detail":"{readStateIndex:21366; appliedIndex:21365; }","duration":"283.272044ms","start":"2026-07-01T08:09:42.846883Z","end":"2026-07-01T08:09:43.130155Z","steps":["trace[1418436083] 'read index received' (duration: 283.08457ms)","trace[1418436083] 'applied index is now lower than readState.Index' (duration: 186.394µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:09:43.130505Z","caller":"traceutil/trace.go:171","msg":"trace[1733519082] transaction","detail":"{read_only:false; response_revision:20665; number_of_response:1; }","duration":"396.297527ms","start":"2026-07-01T08:09:42.734178Z","end":"2026-07-01T08:09:43.130475Z","steps":["trace[1733519082] 'process raft request' (duration: 395.799328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:09:43.130606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.500333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-01T08:09:43.13067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:09:42.734152Z","time spent":"396.42459ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:09:43.130693Z","caller":"traceutil/trace.go:171","msg":"trace[943867224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20665; }","duration":"245.597695ms","start":"2026-07-01T08:09:42.885074Z","end":"2026-07-01T08:09:43.130672Z","steps":["trace[943867224] 'agreement among raft nodes before linearized reading' (duration: 245.361171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:09:43.130732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.48679ms","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-01T08:09:43.130828Z","caller":"traceutil/trace.go:171","msg":"trace[1483691177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20665; }","duration":"236.625074ms","start":"2026-07-01T08:09:42.894181Z","end":"2026-07-01T08:09:43.130806Z","steps":["trace[1483691177] 'agreement among raft nodes before linearized reading' (duration: 236.330917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:09:43.130861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.609846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-01T08:09:43.130817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.460073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:09:43.130987Z","caller":"traceutil/trace.go:171","msg":"trace[873076464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20665; }","duration":"241.785879ms","start":"2026-07-01T08:09:42.889168Z","end":"2026-07-01T08:09:43.130954Z","steps":["trace[873076464] 'agreement among raft nodes before linearized reading' (duration: 241.39694ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:43.131102Z","caller":"traceutil/trace.go:171","msg":"trace[806985157] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:20665; }","duration":"115.759801ms","start":"2026-07-01T08:09:43.015274Z","end":"2026-07-01T08:09:43.131034Z","steps":["trace[806985157] 'agreement among raft nodes before linearized reading' (duration: 115.321011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:09:43.131299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.416448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:417468"} {"level":"info","ts":"2026-07-01T08:09:43.131356Z","caller":"traceutil/trace.go:171","msg":"trace[1517087917] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:20665; }","duration":"284.51225ms","start":"2026-07-01T08:09:42.846828Z","end":"2026-07-01T08:09:43.13134Z","steps":["trace[1517087917] 'agreement among raft nodes before linearized reading' (duration: 283.99953ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:43.289058Z","caller":"traceutil/trace.go:171","msg":"trace[387747656] transaction","detail":"{read_only:false; response_revision:20666; number_of_response:1; }","duration":"154.52319ms","start":"2026-07-01T08:09:43.134505Z","end":"2026-07-01T08:09:43.289028Z","steps":["trace[387747656] 'process raft request' (duration: 129.349097ms)","trace[387747656] 'compare' (duration: 24.595791ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:09:43.289091Z","caller":"traceutil/trace.go:171","msg":"trace[416578875] transaction","detail":"{read_only:false; response_revision:20667; number_of_response:1; }","duration":"153.736263ms","start":"2026-07-01T08:09:43.135323Z","end":"2026-07-01T08:09:43.289059Z","steps":["trace[416578875] 'process raft request' (duration: 153.604601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:09:43.289386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.134762ms","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-01T08:09:43.289473Z","caller":"traceutil/trace.go:171","msg":"trace[1207814393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20668; }","duration":"153.248244ms","start":"2026-07-01T08:09:43.136196Z","end":"2026-07-01T08:09:43.289444Z","steps":["trace[1207814393] 'agreement among raft nodes before linearized reading' (duration: 152.953618ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:43.289074Z","caller":"traceutil/trace.go:171","msg":"trace[1476927187] linearizableReadLoop","detail":"{readStateIndex:21369; appliedIndex:21366; }","duration":"152.823696ms","start":"2026-07-01T08:09:43.136217Z","end":"2026-07-01T08:09:43.28904Z","steps":["trace[1476927187] 'read index received' (duration: 127.646733ms)","trace[1476927187] 'applied index is now lower than readState.Index' (duration: 25.174963ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:09:43.289633Z","caller":"traceutil/trace.go:171","msg":"trace[1715154531] transaction","detail":"{read_only:false; response_revision:20668; number_of_response:1; }","duration":"154.157053ms","start":"2026-07-01T08:09:43.135458Z","end":"2026-07-01T08:09:43.289615Z","steps":["trace[1715154531] 'process raft request' (duration: 153.53081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:09:43.289791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.313014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-01T08:09:43.289849Z","caller":"traceutil/trace.go:171","msg":"trace[754619426] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20668; }","duration":"153.397876ms","start":"2026-07-01T08:09:43.136437Z","end":"2026-07-01T08:09:43.289835Z","steps":["trace[754619426] 'agreement among raft nodes before linearized reading' (duration: 153.207152ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:43.409682Z","caller":"traceutil/trace.go:171","msg":"trace[962592506] transaction","detail":"{read_only:false; response_revision:20669; number_of_response:1; }","duration":"114.28933ms","start":"2026-07-01T08:09:43.295373Z","end":"2026-07-01T08:09:43.409662Z","steps":["trace[962592506] 'process raft request' (duration: 113.492244ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:43.421965Z","caller":"traceutil/trace.go:171","msg":"trace[1072443874] transaction","detail":"{read_only:false; response_revision:20670; number_of_response:1; }","duration":"126.093731ms","start":"2026-07-01T08:09:43.295846Z","end":"2026-07-01T08:09:43.42194Z","steps":["trace[1072443874] 'process raft request' (duration: 125.797865ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:09:43.76574Z","caller":"traceutil/trace.go:171","msg":"trace[2029324526] transaction","detail":"{read_only:false; response_revision:20672; number_of_response:1; }","duration":"151.895866ms","start":"2026-07-01T08:09:43.613813Z","end":"2026-07-01T08:09:43.765709Z","steps":["trace[2029324526] 'process raft request' (duration: 115.820441ms)","trace[2029324526] 'compare' (duration: 35.953713ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:01.086571Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19952} {"level":"info","ts":"2026-07-01T08:13:01.203233Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19952,"took":"113.129646ms","hash":138432464,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":30900224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T08:13:01.203312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":138432464,"revision":19952,"compact-revision":17540} {"level":"warn","ts":"2026-07-01T08:13:34.542791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.891517ms","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-01T08:13:34.542883Z","caller":"traceutil/trace.go:171","msg":"trace[525991112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23092; }","duration":"129.02671ms","start":"2026-07-01T08:13:34.413838Z","end":"2026-07-01T08:13:34.542865Z","steps":["trace[525991112] 'range keys from in-memory index tree' (duration: 128.707094ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.542928Z","caller":"traceutil/trace.go:171","msg":"trace[1550528700] transaction","detail":"{read_only:false; response_revision:23093; number_of_response:1; }","duration":"113.635366ms","start":"2026-07-01T08:13:34.429274Z","end":"2026-07-01T08:13:34.542909Z","steps":["trace[1550528700] 'process raft request' (duration: 59.581374ms)","trace[1550528700] 'compare' (duration: 53.675854ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:13:34.543008Z","caller":"traceutil/trace.go:171","msg":"trace[33025730] linearizableReadLoop","detail":"{readStateIndex:23872; appliedIndex:23871; }","duration":"112.978282ms","start":"2026-07-01T08:13:34.43002Z","end":"2026-07-01T08:13:34.542999Z","steps":["trace[33025730] 'read index received' (duration: 58.816159ms)","trace[33025730] 'applied index is now lower than readState.Index' (duration: 54.161183ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:34.543117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.084634ms","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-01T08:13:34.54314Z","caller":"traceutil/trace.go:171","msg":"trace[288017332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23094; }","duration":"113.143056ms","start":"2026-07-01T08:13:34.429992Z","end":"2026-07-01T08:13:34.543135Z","steps":["trace[288017332] 'agreement among raft nodes before linearized reading' (duration: 113.045964ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.815151Z","caller":"traceutil/trace.go:171","msg":"trace[1769642988] linearizableReadLoop","detail":"{readStateIndex:23874; appliedIndex:23873; }","duration":"272.085877ms","start":"2026-07-01T08:13:34.543019Z","end":"2026-07-01T08:13:34.815105Z","steps":["trace[1769642988] 'read index received' (duration: 271.847421ms)","trace[1769642988] 'applied index is now lower than readState.Index' (duration: 237.166µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:13:34.815613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.21747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-01T08:13:34.815679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.271347ms","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-01T08:13:34.815703Z","caller":"traceutil/trace.go:171","msg":"trace[408061994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23095; }","duration":"353.359012ms","start":"2026-07-01T08:13:34.462321Z","end":"2026-07-01T08:13:34.81568Z","steps":["trace[408061994] 'agreement among raft nodes before linearized reading' (duration: 353.072137ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.815691Z","caller":"traceutil/trace.go:171","msg":"trace[885515736] transaction","detail":"{read_only:false; response_revision:23095; number_of_response:1; }","duration":"293.35384ms","start":"2026-07-01T08:13:34.522314Z","end":"2026-07-01T08:13:34.815668Z","steps":["trace[885515736] 'process raft request' (duration: 292.573774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:34.815745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:34.46231Z","time spent":"353.428264ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T08:13:34.815743Z","caller":"traceutil/trace.go:171","msg":"trace[379052258] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23095; }","duration":"368.376189ms","start":"2026-07-01T08:13:34.447352Z","end":"2026-07-01T08:13:34.815728Z","steps":["trace[379052258] 'agreement among raft nodes before linearized reading' (duration: 368.258737ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:13:34.815959Z","caller":"traceutil/trace.go:171","msg":"trace[672894706] transaction","detail":"{read_only:false; response_revision:23096; number_of_response:1; }","duration":"268.184837ms","start":"2026-07-01T08:13:34.547754Z","end":"2026-07-01T08:13:34.815939Z","steps":["trace[672894706] 'process raft request' (duration: 268.006293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:34.815988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:13:34.447334Z","time spent":"368.644144ms","remote":"127.0.0.1:52600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-01T08:13:34.816105Z","caller":"traceutil/trace.go:171","msg":"trace[1313634684] transaction","detail":"{read_only:false; response_revision:23097; number_of_response:1; }","duration":"267.758078ms","start":"2026-07-01T08:13:34.548321Z","end":"2026-07-01T08:13:34.816079Z","steps":["trace[1313634684] 'process raft request' (duration: 267.573755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:34.816134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.866259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-01T08:13:34.81617Z","caller":"traceutil/trace.go:171","msg":"trace[969605426] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23097; }","duration":"212.923ms","start":"2026-07-01T08:13:34.603236Z","end":"2026-07-01T08:13:34.816159Z","steps":["trace[969605426] 'agreement among raft nodes before linearized reading' (duration: 212.797907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:13:34.816169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.244306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-01T08:13:34.81621Z","caller":"traceutil/trace.go:171","msg":"trace[482131053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23097; }","duration":"210.313647ms","start":"2026-07-01T08:13:34.60588Z","end":"2026-07-01T08:13:34.816193Z","steps":["trace[482131053] 'agreement among raft nodes before linearized reading' (duration: 210.215785ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:14:59.212771Z","caller":"traceutil/trace.go:171","msg":"trace[2136655806] transaction","detail":"{read_only:false; response_revision:24018; number_of_response:1; }","duration":"100.47038ms","start":"2026-07-01T08:14:59.112267Z","end":"2026-07-01T08:14:59.212738Z","steps":["trace[2136655806] 'process raft request' (duration: 100.286056ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:14:59.376104Z","caller":"traceutil/trace.go:171","msg":"trace[1453788360] transaction","detail":"{read_only:false; response_revision:24019; number_of_response:1; }","duration":"217.073077ms","start":"2026-07-01T08:14:59.158991Z","end":"2026-07-01T08:14:59.376064Z","steps":["trace[1453788360] 'process raft request' (duration: 125.933469ms)","trace[1453788360] 'compare' (duration: 90.586597ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:14:59.540735Z","caller":"traceutil/trace.go:171","msg":"trace[436445323] transaction","detail":"{read_only:false; response_revision:24021; number_of_response:1; }","duration":"159.999634ms","start":"2026-07-01T08:14:59.380705Z","end":"2026-07-01T08:14:59.540704Z","steps":["trace[436445323] 'process raft request' (duration: 96.617851ms)","trace[436445323] 'compare' (duration: 63.183559ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:14:59.540989Z","caller":"traceutil/trace.go:171","msg":"trace[1608609978] transaction","detail":"{read_only:false; response_revision:24022; number_of_response:1; }","duration":"159.929683ms","start":"2026-07-01T08:14:59.381034Z","end":"2026-07-01T08:14:59.540963Z","steps":["trace[1608609978] 'process raft request' (duration: 159.615936ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:14:59.541543Z","caller":"traceutil/trace.go:171","msg":"trace[338038025] transaction","detail":"{read_only:false; response_revision:24023; number_of_response:1; }","duration":"159.068986ms","start":"2026-07-01T08:14:59.382446Z","end":"2026-07-01T08:14:59.541515Z","steps":["trace[338038025] 'process raft request' (duration: 158.434473ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:14:59.541691Z","caller":"traceutil/trace.go:171","msg":"trace[233548272] linearizableReadLoop","detail":"{readStateIndex:24825; appliedIndex:24820; }","duration":"158.428131ms","start":"2026-07-01T08:14:59.383231Z","end":"2026-07-01T08:14:59.541659Z","steps":["trace[233548272] 'read index received' (duration: 39.602928ms)","trace[233548272] 'applied index is now lower than readState.Index' (duration: 118.824213ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:14:59.541928Z","caller":"traceutil/trace.go:171","msg":"trace[1948094835] transaction","detail":"{read_only:false; response_revision:24024; number_of_response:1; }","duration":"159.413663ms","start":"2026-07-01T08:14:59.382502Z","end":"2026-07-01T08:14:59.541916Z","steps":["trace[1948094835] 'process raft request' (duration: 158.853571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:14:59.542171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.921401ms","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-01T08:14:59.542224Z","caller":"traceutil/trace.go:171","msg":"trace[2031273527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24024; }","duration":"158.988813ms","start":"2026-07-01T08:14:59.383213Z","end":"2026-07-01T08:14:59.542201Z","steps":["trace[2031273527] 'agreement among raft nodes before linearized reading' (duration: 158.84498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:14:59.855425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.367764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-01T08:14:59.855656Z","caller":"traceutil/trace.go:171","msg":"trace[1351661871] linearizableReadLoop","detail":"{readStateIndex:24827; appliedIndex:24826; }","duration":"172.06767ms","start":"2026-07-01T08:14:59.68356Z","end":"2026-07-01T08:14:59.855628Z","steps":["trace[1351661871] 'read index received' (duration: 93.452µs)","trace[1351661871] 'applied index is now lower than readState.Index' (duration: 171.971078ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:14:59.85575Z","caller":"traceutil/trace.go:171","msg":"trace[1862739369] transaction","detail":"{read_only:false; response_revision:24026; number_of_response:1; }","duration":"121.236363ms","start":"2026-07-01T08:14:59.734494Z","end":"2026-07-01T08:14:59.855731Z","steps":["trace[1862739369] 'process raft request' (duration: 121.042819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:14:59.855851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.259854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:14:59.855949Z","caller":"traceutil/trace.go:171","msg":"trace[37212140] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:24026; }","duration":"172.424667ms","start":"2026-07-01T08:14:59.683502Z","end":"2026-07-01T08:14:59.855927Z","steps":["trace[37212140] 'agreement among raft nodes before linearized reading' (duration: 172.205773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.136837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.149012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:15:00.136961Z","caller":"traceutil/trace.go:171","msg":"trace[189675562] transaction","detail":"{read_only:false; response_revision:24027; number_of_response:1; }","duration":"343.194981ms","start":"2026-07-01T08:14:59.793745Z","end":"2026-07-01T08:15:00.13694Z","steps":["trace[189675562] 'process raft request' (duration: 195.848635ms)","trace[189675562] 'compare' (duration: 146.985029ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:15:00.137026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:14:59.793681Z","time spent":"343.314724ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:15:00.343067Z","caller":"traceutil/trace.go:171","msg":"trace[55418922] transaction","detail":"{read_only:false; response_revision:24028; number_of_response:1; }","duration":"237.565525ms","start":"2026-07-01T08:15:00.105481Z","end":"2026-07-01T08:15:00.343047Z","steps":["trace[55418922] 'process raft request' (duration: 225.808615ms)","trace[55418922] 'compare' (duration: 11.456904ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:15:00.359972Z","caller":"traceutil/trace.go:171","msg":"trace[69284143] linearizableReadLoop","detail":"{readStateIndex:24831; appliedIndex:24829; }","duration":"137.926203ms","start":"2026-07-01T08:15:00.22202Z","end":"2026-07-01T08:15:00.359946Z","steps":["trace[69284143] 'read index received' (duration: 109.285609ms)","trace[69284143] 'applied index is now lower than readState.Index' (duration: 28.639754ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:15:00.360085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.048926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:15:00.360112Z","caller":"traceutil/trace.go:171","msg":"trace[919477201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24029; }","duration":"138.119447ms","start":"2026-07-01T08:15:00.221984Z","end":"2026-07-01T08:15:00.360104Z","steps":["trace[919477201] 'agreement among raft nodes before linearized reading' (duration: 138.058855ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:00.360137Z","caller":"traceutil/trace.go:171","msg":"trace[1372687285] transaction","detail":"{read_only:false; response_revision:24029; number_of_response:1; }","duration":"206.176115ms","start":"2026-07-01T08:15:00.153908Z","end":"2026-07-01T08:15:00.360084Z","steps":["trace[1372687285] 'process raft request' (duration: 205.92632ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:00.540895Z","caller":"traceutil/trace.go:171","msg":"trace[1021309727] transaction","detail":"{read_only:false; response_revision:24030; number_of_response:1; }","duration":"196.382496ms","start":"2026-07-01T08:15:00.34449Z","end":"2026-07-01T08:15:00.540872Z","steps":["trace[1021309727] 'process raft request' (duration: 118.182991ms)","trace[1021309727] 'compare' (duration: 78.039262ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:15:00.541119Z","caller":"traceutil/trace.go:171","msg":"trace[295841414] linearizableReadLoop","detail":"{readStateIndex:24835; appliedIndex:24831; }","duration":"181.079924ms","start":"2026-07-01T08:15:00.360025Z","end":"2026-07-01T08:15:00.541105Z","steps":["trace[295841414] 'read index received' (duration: 102.648915ms)","trace[295841414] 'applied index is now lower than readState.Index' (duration: 78.430339ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:15:00.54112Z","caller":"traceutil/trace.go:171","msg":"trace[291743931] transaction","detail":"{read_only:false; response_revision:24031; number_of_response:1; }","duration":"193.17111ms","start":"2026-07-01T08:15:00.34792Z","end":"2026-07-01T08:15:00.541091Z","steps":["trace[291743931] 'process raft request' (duration: 192.912535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.541348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.495906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:15:00.541395Z","caller":"traceutil/trace.go:171","msg":"trace[1083607252] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24033; }","duration":"292.596228ms","start":"2026-07-01T08:15:00.248788Z","end":"2026-07-01T08:15:00.541384Z","steps":["trace[1083607252] 'agreement among raft nodes before linearized reading' (duration: 292.483806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.54139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.6743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:15:00.541537Z","caller":"traceutil/trace.go:171","msg":"trace[222321739] transaction","detail":"{read_only:false; response_revision:24032; number_of_response:1; }","duration":"192.772543ms","start":"2026-07-01T08:15:00.348755Z","end":"2026-07-01T08:15:00.541528Z","steps":["trace[222321739] 'process raft request' (duration: 192.212232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.541684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.939965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:15:00.541737Z","caller":"traceutil/trace.go:171","msg":"trace[1510805176] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:24033; }","duration":"293.043717ms","start":"2026-07-01T08:15:00.24868Z","end":"2026-07-01T08:15:00.541724Z","steps":["trace[1510805176] 'agreement among raft nodes before linearized reading' (duration: 292.945775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.541721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.938295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:15:00.541777Z","caller":"traceutil/trace.go:171","msg":"trace[557272868] transaction","detail":"{read_only:false; response_revision:24033; number_of_response:1; }","duration":"188.414044ms","start":"2026-07-01T08:15:00.35335Z","end":"2026-07-01T08:15:00.541764Z","steps":["trace[557272868] 'process raft request' (duration: 187.677379ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:00.541818Z","caller":"traceutil/trace.go:171","msg":"trace[1564065445] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:24033; }","duration":"293.058077ms","start":"2026-07-01T08:15:00.248737Z","end":"2026-07-01T08:15:00.541795Z","steps":["trace[1564065445] 'agreement among raft nodes before linearized reading' (duration: 292.884554ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:00.541837Z","caller":"traceutil/trace.go:171","msg":"trace[1545335093] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24033; }","duration":"293.16283ms","start":"2026-07-01T08:15:00.248653Z","end":"2026-07-01T08:15:00.541815Z","steps":["trace[1545335093] 'agreement among raft nodes before linearized reading' (duration: 292.6588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.541392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.216136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:15:00.54201Z","caller":"traceutil/trace.go:171","msg":"trace[2073947932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24033; }","duration":"180.876759ms","start":"2026-07-01T08:15:00.361122Z","end":"2026-07-01T08:15:00.541999Z","steps":["trace[2073947932] 'agreement among raft nodes before linearized reading' (duration: 180.234256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.542184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.73854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-07-01T08:15:00.542347Z","caller":"traceutil/trace.go:171","msg":"trace[943893417] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:24033; }","duration":"289.922924ms","start":"2026-07-01T08:15:00.252393Z","end":"2026-07-01T08:15:00.542316Z","steps":["trace[943893417] 'agreement among raft nodes before linearized reading' (duration: 289.494825ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:00.688028Z","caller":"traceutil/trace.go:171","msg":"trace[1317444846] transaction","detail":"{read_only:false; response_revision:24034; number_of_response:1; }","duration":"139.823881ms","start":"2026-07-01T08:15:00.548171Z","end":"2026-07-01T08:15:00.687995Z","steps":["trace[1317444846] 'process raft request' (duration: 109.963203ms)","trace[1317444846] 'compare' (duration: 29.720576ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:15:00.774489Z","caller":"traceutil/trace.go:171","msg":"trace[742600734] transaction","detail":"{read_only:false; response_revision:24035; number_of_response:1; }","duration":"224.647713ms","start":"2026-07-01T08:15:00.549817Z","end":"2026-07-01T08:15:00.774465Z","steps":["trace[742600734] 'process raft request' (duration: 224.437628ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:00.774839Z","caller":"traceutil/trace.go:171","msg":"trace[1116424846] transaction","detail":"{read_only:false; response_revision:24036; number_of_response:1; }","duration":"223.647192ms","start":"2026-07-01T08:15:00.550914Z","end":"2026-07-01T08:15:00.774562Z","steps":["trace[1116424846] 'process raft request' (duration: 223.485938ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:00.894184Z","caller":"traceutil/trace.go:171","msg":"trace[108689714] linearizableReadLoop","detail":"{readStateIndex:24839; appliedIndex:24838; }","duration":"100.357738ms","start":"2026-07-01T08:15:00.793802Z","end":"2026-07-01T08:15:00.894159Z","steps":["trace[108689714] 'read index received' (duration: 100.204465ms)","trace[108689714] 'applied index is now lower than readState.Index' (duration: 152.333µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:15:00.89435Z","caller":"traceutil/trace.go:171","msg":"trace[328561396] transaction","detail":"{read_only:false; response_revision:24037; number_of_response:1; }","duration":"114.565527ms","start":"2026-07-01T08:15:00.779757Z","end":"2026-07-01T08:15:00.894323Z","steps":["trace[328561396] 'process raft request' (duration: 114.23151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:00.894438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.629843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29714895-64xgs\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-07-01T08:15:00.89447Z","caller":"traceutil/trace.go:171","msg":"trace[1386918791] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29714895-64xgs; range_end:; response_count:1; response_revision:24037; }","duration":"100.738335ms","start":"2026-07-01T08:15:00.793721Z","end":"2026-07-01T08:15:00.89446Z","steps":["trace[1386918791] 'agreement among raft nodes before linearized reading' (duration: 100.5264ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:01.091936Z","caller":"traceutil/trace.go:171","msg":"trace[1857124647] linearizableReadLoop","detail":"{readStateIndex:24842; appliedIndex:24839; }","duration":"172.744954ms","start":"2026-07-01T08:15:00.919166Z","end":"2026-07-01T08:15:01.091911Z","steps":["trace[1857124647] 'read index received' (duration: 8.986283ms)","trace[1857124647] 'applied index is now lower than readState.Index' (duration: 163.757411ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:15:01.091965Z","caller":"traceutil/trace.go:171","msg":"trace[1779535240] transaction","detail":"{read_only:false; response_revision:24039; number_of_response:1; }","duration":"196.556989ms","start":"2026-07-01T08:15:00.895376Z","end":"2026-07-01T08:15:01.091933Z","steps":["trace[1779535240] 'process raft request' (duration: 196.287714ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:15:01.092072Z","caller":"traceutil/trace.go:171","msg":"trace[1051651155] transaction","detail":"{read_only:false; response_revision:24040; number_of_response:1; }","duration":"188.541367ms","start":"2026-07-01T08:15:00.90352Z","end":"2026-07-01T08:15:01.092061Z","steps":["trace[1051651155] 'process raft request' (duration: 188.319212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:15:01.092367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.136082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:15:01.092459Z","caller":"traceutil/trace.go:171","msg":"trace[792176532] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24040; }","duration":"173.278344ms","start":"2026-07-01T08:15:00.919159Z","end":"2026-07-01T08:15:01.092437Z","steps":["trace[792176532] 'agreement among raft nodes before linearized reading' (duration: 173.026559ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:16:48.277941Z","caller":"traceutil/trace.go:171","msg":"trace[841279330] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"104.110724ms","start":"2026-07-01T08:16:48.173808Z","end":"2026-07-01T08:16:48.277919Z","steps":["trace[841279330] 'process raft request' (duration: 99.410038ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:16:59.579904Z","caller":"traceutil/trace.go:171","msg":"trace[1879177061] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"215.552977ms","start":"2026-07-01T08:16:59.364293Z","end":"2026-07-01T08:16:59.579846Z","steps":["trace[1879177061] 'process raft request' (duration: 215.262241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:03.490845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.078058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:17:03.490947Z","caller":"traceutil/trace.go:171","msg":"trace[56218719] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25228; }","duration":"190.234861ms","start":"2026-07-01T08:17:03.30069Z","end":"2026-07-01T08:17:03.490925Z","steps":["trace[56218719] 'count revisions from in-memory index tree' (duration: 189.932364ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:03.741738Z","caller":"traceutil/trace.go:171","msg":"trace[1988368134] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"139.935844ms","start":"2026-07-01T08:17:03.601775Z","end":"2026-07-01T08:17:03.741711Z","steps":["trace[1988368134] 'process raft request' (duration: 122.724804ms)","trace[1988368134] 'compare' (duration: 17.050337ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:03.892714Z","caller":"traceutil/trace.go:171","msg":"trace[933703472] linearizableReadLoop","detail":"{readStateIndex:26065; appliedIndex:26064; }","duration":"109.311819ms","start":"2026-07-01T08:17:03.783367Z","end":"2026-07-01T08:17:03.892679Z","steps":["trace[933703472] 'read index received' (duration: 108.588624ms)","trace[933703472] 'applied index is now lower than readState.Index' (duration: 721.445µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:03.89274Z","caller":"traceutil/trace.go:171","msg":"trace[285298684] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"117.15867ms","start":"2026-07-01T08:17:03.775552Z","end":"2026-07-01T08:17:03.892711Z","steps":["trace[285298684] 'process raft request' (duration: 116.347963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:03.892869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.465473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:03.892918Z","caller":"traceutil/trace.go:171","msg":"trace[45937436] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:25231; }","duration":"109.586835ms","start":"2026-07-01T08:17:03.78332Z","end":"2026-07-01T08:17:03.892906Z","steps":["trace[45937436] 'agreement among raft nodes before linearized reading' (duration: 109.472642ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:04.393669Z","caller":"traceutil/trace.go:171","msg":"trace[1767494525] linearizableReadLoop","detail":"{readStateIndex:26067; appliedIndex:26066; }","duration":"172.876697ms","start":"2026-07-01T08:17:04.220768Z","end":"2026-07-01T08:17:04.393645Z","steps":["trace[1767494525] 'read index received' (duration: 165.819882ms)","trace[1767494525] 'applied index is now lower than readState.Index' (duration: 7.053275ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:04.39453Z","caller":"traceutil/trace.go:171","msg":"trace[2000678892] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"191.470807ms","start":"2026-07-01T08:17:04.202434Z","end":"2026-07-01T08:17:04.393905Z","steps":["trace[2000678892] 'process raft request' (duration: 184.174868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:04.394719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.915858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:04.394809Z","caller":"traceutil/trace.go:171","msg":"trace[1243390085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25233; }","duration":"174.064511ms","start":"2026-07-01T08:17:04.220722Z","end":"2026-07-01T08:17:04.394787Z","steps":["trace[1243390085] 'agreement among raft nodes before linearized reading' (duration: 173.868637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:04.398009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.818415ms","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-01T08:17:04.39807Z","caller":"traceutil/trace.go:171","msg":"trace[1727539537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25234; }","duration":"145.931377ms","start":"2026-07-01T08:17:04.252126Z","end":"2026-07-01T08:17:04.398057Z","steps":["trace[1727539537] 'agreement among raft nodes before linearized reading' (duration: 145.748624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:04.398543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.963172ms","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-01T08:17:04.398575Z","caller":"traceutil/trace.go:171","msg":"trace[1008830023] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25234; }","duration":"107.034184ms","start":"2026-07-01T08:17:04.291533Z","end":"2026-07-01T08:17:04.398567Z","steps":["trace[1008830023] 'agreement among raft nodes before linearized reading' (duration: 106.987523ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:04.523988Z","caller":"traceutil/trace.go:171","msg":"trace[1957808420] linearizableReadLoop","detail":"{readStateIndex:26070; appliedIndex:26068; }","duration":"105.299479ms","start":"2026-07-01T08:17:04.418671Z","end":"2026-07-01T08:17:04.523971Z","steps":["trace[1957808420] 'read index received' (duration: 95.933668ms)","trace[1957808420] 'applied index is now lower than readState.Index' (duration: 9.365171ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:04.524298Z","caller":"traceutil/trace.go:171","msg":"trace[38868419] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"121.996848ms","start":"2026-07-01T08:17:04.402294Z","end":"2026-07-01T08:17:04.524291Z","steps":["trace[38868419] 'process raft request' (duration: 121.58398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:04.524456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.771968ms","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-01T08:17:04.524505Z","caller":"traceutil/trace.go:171","msg":"trace[841592100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25235; }","duration":"105.8588ms","start":"2026-07-01T08:17:04.418636Z","end":"2026-07-01T08:17:04.524495Z","steps":["trace[841592100] 'agreement among raft nodes before linearized reading' (duration: 105.707507ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:07.007827Z","caller":"traceutil/trace.go:171","msg":"trace[1059439334] transaction","detail":"{read_only:false; response_revision:25254; number_of_response:1; }","duration":"126.695366ms","start":"2026-07-01T08:17:06.881106Z","end":"2026-07-01T08:17:07.007802Z","steps":["trace[1059439334] 'process raft request' (duration: 126.535952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:07.458627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.543197ms","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-01T08:17:07.458689Z","caller":"traceutil/trace.go:171","msg":"trace[392203585] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25254; }","duration":"364.650869ms","start":"2026-07-01T08:17:07.094024Z","end":"2026-07-01T08:17:07.458675Z","steps":["trace[392203585] 'range keys from in-memory index tree' (duration: 364.402033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:07.458717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:07.094001Z","time spent":"364.710431ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-07-01T08:17:07.4591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.802233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-07-01T08:17:07.459208Z","caller":"traceutil/trace.go:171","msg":"trace[1779253231] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25254; }","duration":"291.945425ms","start":"2026-07-01T08:17:07.167235Z","end":"2026-07-01T08:17:07.45918Z","steps":["trace[1779253231] 'range keys from in-memory index tree' (duration: 291.6352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:07.459468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.337978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:07.459681Z","caller":"traceutil/trace.go:171","msg":"trace[2038929153] linearizableReadLoop","detail":"{readStateIndex:26090; appliedIndex:26089; }","duration":"238.516566ms","start":"2026-07-01T08:17:07.221134Z","end":"2026-07-01T08:17:07.459651Z","steps":["trace[2038929153] 'read index received' (duration: 108.919082ms)","trace[2038929153] 'applied index is now lower than readState.Index' (duration: 129.593804ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:07.459853Z","caller":"traceutil/trace.go:171","msg":"trace[432700043] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"265.387134ms","start":"2026-07-01T08:17:07.194449Z","end":"2026-07-01T08:17:07.459836Z","steps":["trace[432700043] 'process raft request' (duration: 135.524426ms)","trace[432700043] 'compare' (duration: 129.085393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:07.459945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.797421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:07.460059Z","caller":"traceutil/trace.go:171","msg":"trace[1904345213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25255; }","duration":"238.917553ms","start":"2026-07-01T08:17:07.221102Z","end":"2026-07-01T08:17:07.460019Z","steps":["trace[1904345213] 'agreement among raft nodes before linearized reading' (duration: 238.77886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:07.763843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.271998ms","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-01T08:17:07.763921Z","caller":"traceutil/trace.go:171","msg":"trace[1475009851] linearizableReadLoop","detail":"{readStateIndex:26092; appliedIndex:26091; }","duration":"100.021411ms","start":"2026-07-01T08:17:07.663867Z","end":"2026-07-01T08:17:07.763888Z","steps":["trace[1475009851] 'read index received' (duration: 86.504235ms)","trace[1475009851] 'applied index is now lower than readState.Index' (duration: 13.515546ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:07.763966Z","caller":"traceutil/trace.go:171","msg":"trace[325884727] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25256; }","duration":"154.463512ms","start":"2026-07-01T08:17:07.609466Z","end":"2026-07-01T08:17:07.76393Z","steps":["trace[325884727] 'count revisions from in-memory index tree' (duration: 154.100654ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:07.76398Z","caller":"traceutil/trace.go:171","msg":"trace[1405636039] transaction","detail":"{read_only:false; response_revision:25257; number_of_response:1; }","duration":"139.331433ms","start":"2026-07-01T08:17:07.624614Z","end":"2026-07-01T08:17:07.763945Z","steps":["trace[1405636039] 'process raft request' (duration: 125.905308ms)","trace[1405636039] 'compare' (duration: 13.166009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:07.764091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.234135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:17:07.764145Z","caller":"traceutil/trace.go:171","msg":"trace[830556863] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25257; }","duration":"100.330557ms","start":"2026-07-01T08:17:07.663797Z","end":"2026-07-01T08:17:07.764128Z","steps":["trace[830556863] 'agreement among raft nodes before linearized reading' (duration: 100.244976ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:08.033001Z","caller":"traceutil/trace.go:171","msg":"trace[1414962789] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"277.773847ms","start":"2026-07-01T08:17:07.755189Z","end":"2026-07-01T08:17:08.032963Z","steps":["trace[1414962789] 'process raft request' (duration: 277.595584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.207393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:08.207909Z","caller":"traceutil/trace.go:171","msg":"trace[503118179] transaction","detail":"{read_only:false; response_revision:25259; number_of_response:1; }","duration":"430.424151ms","start":"2026-07-01T08:17:07.777452Z","end":"2026-07-01T08:17:08.207876Z","steps":["trace[503118179] 'process raft request' (duration: 293.759313ms)","trace[503118179] 'compare' (duration: 135.750599ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:08.208157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:07.777426Z","time spent":"430.663725ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:17:08.208648Z","caller":"traceutil/trace.go:171","msg":"trace[1413755330] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"301.884378ms","start":"2026-07-01T08:17:07.906753Z","end":"2026-07-01T08:17:08.208637Z","steps":["trace[1413755330] 'process raft request' (duration: 301.063702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.208723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:07.906714Z","time spent":"301.970909ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:17:08.528829Z","caller":"traceutil/trace.go:171","msg":"trace[125751409] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"108.482294ms","start":"2026-07-01T08:17:08.420307Z","end":"2026-07-01T08:17:08.528789Z","steps":["trace[125751409] 'process raft request' (duration: 108.216529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.832419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.61036ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:08.832564Z","caller":"traceutil/trace.go:171","msg":"trace[343982129] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25262; }","duration":"243.765743ms","start":"2026-07-01T08:17:08.588779Z","end":"2026-07-01T08:17:08.832545Z","steps":["trace[343982129] 'range keys from in-memory index tree' (duration: 243.591909ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:08.836637Z","caller":"traceutil/trace.go:171","msg":"trace[336403167] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"416.252342ms","start":"2026-07-01T08:17:08.420347Z","end":"2026-07-01T08:17:08.8366Z","steps":["trace[336403167] 'process raft request' (duration: 415.968755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.836868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:08.420325Z","time spent":"416.415265ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:08.836983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.49604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-07-01T08:17:08.837153Z","caller":"traceutil/trace.go:171","msg":"trace[1340308920] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25263; }","duration":"402.731476ms","start":"2026-07-01T08:17:08.434392Z","end":"2026-07-01T08:17:08.837123Z","steps":["trace[1340308920] 'agreement among raft nodes before linearized reading' (duration: 402.316967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.837245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:08.434377Z","time spent":"402.853748ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-01T08:17:08.837295Z","caller":"traceutil/trace.go:171","msg":"trace[107861118] linearizableReadLoop","detail":"{readStateIndex:26098; appliedIndex:26096; }","duration":"402.119692ms","start":"2026-07-01T08:17:08.434431Z","end":"2026-07-01T08:17:08.83655Z","steps":["trace[107861118] 'read index received' (duration: 94.054249ms)","trace[107861118] 'applied index is now lower than readState.Index' (duration: 308.064043ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:08.847536Z","caller":"traceutil/trace.go:171","msg":"trace[1806611423] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"259.614755ms","start":"2026-07-01T08:17:08.587901Z","end":"2026-07-01T08:17:08.847515Z","steps":["trace[1806611423] 'process raft request' (duration: 259.414141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.847768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.954488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-01T08:17:08.847812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.011871ms","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-01T08:17:08.847838Z","caller":"traceutil/trace.go:171","msg":"trace[639007025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25264; }","duration":"300.041292ms","start":"2026-07-01T08:17:08.547784Z","end":"2026-07-01T08:17:08.847825Z","steps":["trace[639007025] 'agreement among raft nodes before linearized reading' (duration: 299.870837ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:08.847851Z","caller":"traceutil/trace.go:171","msg":"trace[437990090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25264; }","duration":"301.090192ms","start":"2026-07-01T08:17:08.546749Z","end":"2026-07-01T08:17:08.847839Z","steps":["trace[437990090] 'agreement among raft nodes before linearized reading' (duration: 300.970859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.847888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:08.547776Z","time spent":"300.105393ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T08:17:08.847891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:08.546725Z","time spent":"301.157274ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T08:17:08.847969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.675994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T08:17:08.847969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.74545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:08.84803Z","caller":"traceutil/trace.go:171","msg":"trace[882170808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25264; }","duration":"301.784547ms","start":"2026-07-01T08:17:08.54623Z","end":"2026-07-01T08:17:08.848015Z","steps":["trace[882170808] 'agreement among raft nodes before linearized reading' (duration: 301.564812ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:08.848067Z","caller":"traceutil/trace.go:171","msg":"trace[1240173724] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25264; }","duration":"261.904613ms","start":"2026-07-01T08:17:08.586136Z","end":"2026-07-01T08:17:08.84804Z","steps":["trace[1240173724] 'agreement among raft nodes before linearized reading' (duration: 261.718589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.84807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:08.54621Z","time spent":"301.851958ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T08:17:08.847775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.918301ms","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-01T08:17:08.848664Z","caller":"traceutil/trace.go:171","msg":"trace[1701550731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25264; }","duration":"310.856391ms","start":"2026-07-01T08:17:08.537787Z","end":"2026-07-01T08:17:08.848643Z","steps":["trace[1701550731] 'agreement among raft nodes before linearized reading' (duration: 309.720167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:08.848763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:08.537764Z","time spent":"310.985253ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T08:17:09.004976Z","caller":"traceutil/trace.go:171","msg":"trace[1181087106] linearizableReadLoop","detail":"{readStateIndex:26104; appliedIndex:26100; }","duration":"123.470219ms","start":"2026-07-01T08:17:08.881486Z","end":"2026-07-01T08:17:09.004957Z","steps":["trace[1181087106] 'read index received' (duration: 123.268015ms)","trace[1181087106] 'applied index is now lower than readState.Index' (duration: 201.384µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:09.005231Z","caller":"traceutil/trace.go:171","msg":"trace[995770792] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"152.551792ms","start":"2026-07-01T08:17:08.85267Z","end":"2026-07-01T08:17:09.005222Z","steps":["trace[995770792] 'process raft request' (duration: 152.081333ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:09.00539Z","caller":"traceutil/trace.go:171","msg":"trace[1656967018] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"152.337408ms","start":"2026-07-01T08:17:08.853019Z","end":"2026-07-01T08:17:09.005356Z","steps":["trace[1656967018] 'process raft request' (duration: 151.884569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:09.005457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.922388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:09.005574Z","caller":"traceutil/trace.go:171","msg":"trace[1936448010] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25269; }","duration":"124.089901ms","start":"2026-07-01T08:17:08.881451Z","end":"2026-07-01T08:17:09.005541Z","steps":["trace[1936448010] 'agreement among raft nodes before linearized reading' (duration: 123.853546ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:09.005596Z","caller":"traceutil/trace.go:171","msg":"trace[690656253] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"152.801918ms","start":"2026-07-01T08:17:08.852778Z","end":"2026-07-01T08:17:09.00558Z","steps":["trace[690656253] 'process raft request' (duration: 152.092693ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:09.005557Z","caller":"traceutil/trace.go:171","msg":"trace[1909163435] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"152.405539ms","start":"2026-07-01T08:17:08.853113Z","end":"2026-07-01T08:17:09.005519Z","steps":["trace[1909163435] 'process raft request' (duration: 151.812107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:09.333152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.928714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"warn","ts":"2026-07-01T08:17:09.333195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.855291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T08:17:09.333202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.409747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:17:09.333245Z","caller":"traceutil/trace.go:171","msg":"trace[269119670] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25270; }","duration":"239.072697ms","start":"2026-07-01T08:17:09.094147Z","end":"2026-07-01T08:17:09.33322Z","steps":["trace[269119670] 'range keys from in-memory index tree' (duration: 238.685159ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:09.333256Z","caller":"traceutil/trace.go:171","msg":"trace[528547292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25270; }","duration":"109.940803ms","start":"2026-07-01T08:17:09.2233Z","end":"2026-07-01T08:17:09.333241Z","steps":["trace[528547292] 'range keys from in-memory index tree' (duration: 109.80906ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:09.333281Z","caller":"traceutil/trace.go:171","msg":"trace[708790588] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25270; }","duration":"122.54257ms","start":"2026-07-01T08:17:09.210716Z","end":"2026-07-01T08:17:09.333259Z","steps":["trace[708790588] 'count revisions from in-memory index tree' (duration: 122.303505ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:10.287299Z","caller":"traceutil/trace.go:171","msg":"trace[1180755201] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"247.696032ms","start":"2026-07-01T08:17:10.039582Z","end":"2026-07-01T08:17:10.287278Z","steps":["trace[1180755201] 'process raft request' (duration: 247.573359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:10.515042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.233145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:10.515218Z","caller":"traceutil/trace.go:171","msg":"trace[711899602] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"300.933209ms","start":"2026-07-01T08:17:10.214256Z","end":"2026-07-01T08:17:10.515189Z","steps":["trace[711899602] 'process raft request' (duration: 151.453049ms)","trace[711899602] 'compare' (duration: 149.106163ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:10.515278Z","caller":"traceutil/trace.go:171","msg":"trace[1417430541] linearizableReadLoop","detail":"{readStateIndex:26111; appliedIndex:26109; }","duration":"279.755718ms","start":"2026-07-01T08:17:10.235512Z","end":"2026-07-01T08:17:10.515268Z","steps":["trace[1417430541] 'read index received' (duration: 51.717065ms)","trace[1417430541] 'applied index is now lower than readState.Index' (duration: 228.037493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:10.515354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:10.214228Z","time spent":"301.055662ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:17:10.515236Z","caller":"traceutil/trace.go:171","msg":"trace[2004203322] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"141.675281ms","start":"2026-07-01T08:17:10.373542Z","end":"2026-07-01T08:17:10.515218Z","steps":["trace[2004203322] 'process raft request' (duration: 141.615779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:10.51565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.132805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-01T08:17:10.515688Z","caller":"traceutil/trace.go:171","msg":"trace[288380691] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25276; }","duration":"280.209567ms","start":"2026-07-01T08:17:10.235468Z","end":"2026-07-01T08:17:10.515677Z","steps":["trace[288380691] 'agreement among raft nodes before linearized reading' (duration: 280.033103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:10.794151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.200679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:10.794236Z","caller":"traceutil/trace.go:171","msg":"trace[276828310] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"259.83093ms","start":"2026-07-01T08:17:10.534388Z","end":"2026-07-01T08:17:10.794219Z","steps":["trace[276828310] 'process raft request' (duration: 122.500729ms)","trace[276828310] 'compare' (duration: 137.118957ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:11.177719Z","caller":"traceutil/trace.go:171","msg":"trace[1579103903] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"331.805739ms","start":"2026-07-01T08:17:10.845883Z","end":"2026-07-01T08:17:11.177689Z","steps":["trace[1579103903] 'process raft request' (duration: 331.601845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.177911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:10.845863Z","time spent":"331.955521ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:11.415825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.796568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:11.416044Z","caller":"traceutil/trace.go:171","msg":"trace[1229731623] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"562.369521ms","start":"2026-07-01T08:17:10.853641Z","end":"2026-07-01T08:17:11.416011Z","steps":["trace[1229731623] 'process raft request' (duration: 407.254968ms)","trace[1229731623] 'compare' (duration: 154.619963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:11.416228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:10.853625Z","time spent":"562.500354ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:11.416286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.39611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-01T08:17:11.416338Z","caller":"traceutil/trace.go:171","msg":"trace[67067070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25281; }","duration":"406.447441ms","start":"2026-07-01T08:17:11.009878Z","end":"2026-07-01T08:17:11.416325Z","steps":["trace[67067070] 'agreement among raft nodes before linearized reading' (duration: 406.227126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.416386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.009816Z","time spent":"406.560684ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-01T08:17:11.416072Z","caller":"traceutil/trace.go:171","msg":"trace[2060597105] linearizableReadLoop","detail":"{readStateIndex:26116; appliedIndex:26114; }","duration":"406.161735ms","start":"2026-07-01T08:17:11.009886Z","end":"2026-07-01T08:17:11.416048Z","steps":["trace[2060597105] 'read index received' (duration: 167.574078ms)","trace[2060597105] 'applied index is now lower than readState.Index' (duration: 238.586097ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:11.416616Z","caller":"traceutil/trace.go:171","msg":"trace[25480643] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"367.537627ms","start":"2026-07-01T08:17:11.049046Z","end":"2026-07-01T08:17:11.416583Z","steps":["trace[25480643] 'process raft request' (duration: 366.909364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.416645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.350485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T08:17:11.416641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.385951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T08:17:11.416637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.860123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:11.416695Z","caller":"traceutil/trace.go:171","msg":"trace[262311700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25281; }","duration":"194.430057ms","start":"2026-07-01T08:17:11.222249Z","end":"2026-07-01T08:17:11.416679Z","steps":["trace[262311700] 'agreement among raft nodes before linearized reading' (duration: 194.368396ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:11.416701Z","caller":"traceutil/trace.go:171","msg":"trace[2002395589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25281; }","duration":"406.465062ms","start":"2026-07-01T08:17:11.01022Z","end":"2026-07-01T08:17:11.416685Z","steps":["trace[2002395589] 'agreement among raft nodes before linearized reading' (duration: 406.283957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.416717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.381651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-01T08:17:11.416742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.010213Z","time spent":"406.521494ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T08:17:11.416738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.049012Z","time spent":"367.664131ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:11.416731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.37232ms","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-01T08:17:11.416788Z","caller":"traceutil/trace.go:171","msg":"trace[1449752850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25281; }","duration":"406.468653ms","start":"2026-07-01T08:17:11.010302Z","end":"2026-07-01T08:17:11.416771Z","steps":["trace[1449752850] 'agreement among raft nodes before linearized reading' (duration: 406.225937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.416838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.010297Z","time spent":"406.530494ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-01T08:17:11.416837Z","caller":"traceutil/trace.go:171","msg":"trace[911822837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25281; }","duration":"406.517833ms","start":"2026-07-01T08:17:11.010296Z","end":"2026-07-01T08:17:11.416813Z","steps":["trace[911822837] 'agreement among raft nodes before linearized reading' (duration: 406.227837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.416948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.010279Z","time spent":"406.655076ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T08:17:11.416745Z","caller":"traceutil/trace.go:171","msg":"trace[1460748847] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25281; }","duration":"193.022498ms","start":"2026-07-01T08:17:11.2237Z","end":"2026-07-01T08:17:11.416722Z","steps":["trace[1460748847] 'agreement among raft nodes before linearized reading' (duration: 192.837113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.416559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.36238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:11.41717Z","caller":"traceutil/trace.go:171","msg":"trace[1703299330] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25281; }","duration":"335.012514ms","start":"2026-07-01T08:17:11.082133Z","end":"2026-07-01T08:17:11.417145Z","steps":["trace[1703299330] 'agreement among raft nodes before linearized reading' (duration: 334.37544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.417228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.082095Z","time spent":"335.115846ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T08:17:11.720854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.007744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:11.720962Z","caller":"traceutil/trace.go:171","msg":"trace[486722818] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"299.01321ms","start":"2026-07-01T08:17:11.421927Z","end":"2026-07-01T08:17:11.72094Z","steps":["trace[486722818] 'process raft request' (duration: 137.829763ms)","trace[486722818] 'compare' (duration: 160.532524ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:11.723861Z","caller":"traceutil/trace.go:171","msg":"trace[2090498823] linearizableReadLoop","detail":"{readStateIndex:26122; appliedIndex:26118; }","duration":"129.903049ms","start":"2026-07-01T08:17:11.593941Z","end":"2026-07-01T08:17:11.723845Z","steps":["trace[2090498823] 'read index received' (duration: 129.617253ms)","trace[2090498823] 'applied index is now lower than readState.Index' (duration: 285.086µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:11.723928Z","caller":"traceutil/trace.go:171","msg":"trace[2143699652] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"300.933919ms","start":"2026-07-01T08:17:11.422983Z","end":"2026-07-01T08:17:11.723917Z","steps":["trace[2143699652] 'process raft request' (duration: 300.784946ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:11.724Z","caller":"traceutil/trace.go:171","msg":"trace[698501734] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"214.650838ms","start":"2026-07-01T08:17:11.509305Z","end":"2026-07-01T08:17:11.723955Z","steps":["trace[698501734] 'process raft request' (duration: 214.500605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.724104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.422977Z","time spent":"300.99919ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:11.724104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.135754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:11.724118Z","caller":"traceutil/trace.go:171","msg":"trace[317422858] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"301.371867ms","start":"2026-07-01T08:17:11.42269Z","end":"2026-07-01T08:17:11.724061Z","steps":["trace[317422858] 'process raft request' (duration: 301.038481ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:11.723851Z","caller":"traceutil/trace.go:171","msg":"trace[1591254018] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"301.126832ms","start":"2026-07-01T08:17:11.422685Z","end":"2026-07-01T08:17:11.723812Z","steps":["trace[1591254018] 'process raft request' (duration: 300.966059ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:11.724225Z","caller":"traceutil/trace.go:171","msg":"trace[1627203155] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25286; }","duration":"130.256716ms","start":"2026-07-01T08:17:11.5939Z","end":"2026-07-01T08:17:11.724156Z","steps":["trace[1627203155] 'agreement among raft nodes before linearized reading' (duration: 130.054522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:11.724281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.422673Z","time spent":"301.51842ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:11.724307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:11.422666Z","time spent":"301.575631ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:17:12.816865Z","caller":"traceutil/trace.go:171","msg":"trace[1841916319] linearizableReadLoop","detail":"{readStateIndex:26126; appliedIndex:26125; }","duration":"229.323908ms","start":"2026-07-01T08:17:12.587516Z","end":"2026-07-01T08:17:12.81684Z","steps":["trace[1841916319] 'read index received' (duration: 229.192116ms)","trace[1841916319] 'applied index is now lower than readState.Index' (duration: 130.722µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:12.816998Z","caller":"traceutil/trace.go:171","msg":"trace[899147116] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"295.490608ms","start":"2026-07-01T08:17:12.521476Z","end":"2026-07-01T08:17:12.816966Z","steps":["trace[899147116] 'process raft request' (duration: 295.198182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:12.817184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.651135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-01T08:17:12.817261Z","caller":"traceutil/trace.go:171","msg":"trace[269395810] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25290; }","duration":"229.743086ms","start":"2026-07-01T08:17:12.587497Z","end":"2026-07-01T08:17:12.81724Z","steps":["trace[269395810] 'agreement among raft nodes before linearized reading' (duration: 229.475071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:13.044079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.503509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:13.044278Z","caller":"traceutil/trace.go:171","msg":"trace[188580608] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"220.728783ms","start":"2026-07-01T08:17:12.823532Z","end":"2026-07-01T08:17:13.044261Z","steps":["trace[188580608] 'process raft request' (duration: 220.663541ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:13.044216Z","caller":"traceutil/trace.go:171","msg":"trace[392757711] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"243.356184ms","start":"2026-07-01T08:17:12.800829Z","end":"2026-07-01T08:17:13.044185Z","steps":["trace[392757711] 'process raft request' (duration: 122.653061ms)","trace[392757711] 'compare' (duration: 120.146952ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:13.04453Z","caller":"traceutil/trace.go:171","msg":"trace[544734401] linearizableReadLoop","detail":"{readStateIndex:26127; appliedIndex:26126; }","duration":"227.589283ms","start":"2026-07-01T08:17:12.81693Z","end":"2026-07-01T08:17:13.04452Z","steps":["trace[544734401] 'read index received' (duration: 106.476302ms)","trace[544734401] 'applied index is now lower than readState.Index' (duration: 121.111771ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:13.044602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.95414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:13.044637Z","caller":"traceutil/trace.go:171","msg":"trace[997401949] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25292; }","duration":"351.035302ms","start":"2026-07-01T08:17:12.693593Z","end":"2026-07-01T08:17:13.044628Z","steps":["trace[997401949] 'agreement among raft nodes before linearized reading' (duration: 350.96391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:13.04467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:12.693573Z","time spent":"351.087662ms","remote":"127.0.0.1:54688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-07-01T08:17:13.29805Z","caller":"traceutil/trace.go:171","msg":"trace[1133065578] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"112.372542ms","start":"2026-07-01T08:17:13.185623Z","end":"2026-07-01T08:17:13.297995Z","steps":["trace[1133065578] 'process raft request' (duration: 112.121807ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:13.709781Z","caller":"traceutil/trace.go:171","msg":"trace[545246374] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"286.888532ms","start":"2026-07-01T08:17:13.422853Z","end":"2026-07-01T08:17:13.709742Z","steps":["trace[545246374] 'process raft request' (duration: 286.558306ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:13.711286Z","caller":"traceutil/trace.go:171","msg":"trace[1157683990] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"287.682218ms","start":"2026-07-01T08:17:13.423559Z","end":"2026-07-01T08:17:13.711241Z","steps":["trace[1157683990] 'process raft request' (duration: 287.358231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:24.764559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.987382ms","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-01T08:17:24.764612Z","caller":"traceutil/trace.go:171","msg":"trace[188410727] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25439; }","duration":"132.088254ms","start":"2026-07-01T08:17:24.632511Z","end":"2026-07-01T08:17:24.764599Z","steps":["trace[188410727] 'range keys from in-memory index tree' (duration: 131.88514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:24.764634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.830873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f9b34057-a6d7-469e-985d-480b8c05da76\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-01T08:17:24.764686Z","caller":"traceutil/trace.go:171","msg":"trace[1583732590] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f9b34057-a6d7-469e-985d-480b8c05da76; range_end:; response_count:1; response_revision:25439; }","duration":"139.914454ms","start":"2026-07-01T08:17:24.624756Z","end":"2026-07-01T08:17:24.764671Z","steps":["trace[1583732590] 'range keys from in-memory index tree' (duration: 139.656769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:24.765069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.577113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f9b34057-a6d7-469e-985d-480b8c05da76\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-01T08:17:24.765363Z","caller":"traceutil/trace.go:171","msg":"trace[1896913822] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f9b34057-a6d7-469e-985d-480b8c05da76; range_end:; response_count:1; response_revision:25439; }","duration":"103.726707ms","start":"2026-07-01T08:17:24.661416Z","end":"2026-07-01T08:17:24.765143Z","steps":["trace[1896913822] 'range keys from in-memory index tree' (duration: 102.922598ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:25.044803Z","caller":"traceutil/trace.go:171","msg":"trace[1644816024] linearizableReadLoop","detail":"{readStateIndex:26279; appliedIndex:26278; }","duration":"260.140817ms","start":"2026-07-01T08:17:24.784635Z","end":"2026-07-01T08:17:25.044775Z","steps":["trace[1644816024] 'read index received' (duration: 259.953843ms)","trace[1644816024] 'applied index is now lower than readState.Index' (duration: 185.664µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:25.044862Z","caller":"traceutil/trace.go:171","msg":"trace[656554705] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"271.54362ms","start":"2026-07-01T08:17:24.773282Z","end":"2026-07-01T08:17:25.044826Z","steps":["trace[656554705] 'process raft request' (duration: 271.330965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:25.045032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.382031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-01T08:17:25.045074Z","caller":"traceutil/trace.go:171","msg":"trace[253005748] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25440; }","duration":"260.478543ms","start":"2026-07-01T08:17:24.784582Z","end":"2026-07-01T08:17:25.045061Z","steps":["trace[253005748] 'agreement among raft nodes before linearized reading' (duration: 260.31968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:25.057369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.404918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-01T08:17:25.057376Z","caller":"traceutil/trace.go:171","msg":"trace[190154001] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"197.703824ms","start":"2026-07-01T08:17:24.859636Z","end":"2026-07-01T08:17:25.05734Z","steps":["trace[190154001] 'process raft request' (duration: 197.491619ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:25.057433Z","caller":"traceutil/trace.go:171","msg":"trace[1910260106] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:25441; }","duration":"223.52435ms","start":"2026-07-01T08:17:24.833894Z","end":"2026-07-01T08:17:25.057418Z","steps":["trace[1910260106] 'agreement among raft nodes before linearized reading' (duration: 223.350437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:25.31987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.086569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:25.319988Z","caller":"traceutil/trace.go:171","msg":"trace[2094505567] linearizableReadLoop","detail":"{readStateIndex:26281; appliedIndex:26280; }","duration":"211.939864ms","start":"2026-07-01T08:17:25.108029Z","end":"2026-07-01T08:17:25.319968Z","steps":["trace[2094505567] 'read index received' (duration: 93.61341ms)","trace[2094505567] 'applied index is now lower than readState.Index' (duration: 118.325184ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:25.320167Z","caller":"traceutil/trace.go:171","msg":"trace[2111490591] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"224.208404ms","start":"2026-07-01T08:17:25.09593Z","end":"2026-07-01T08:17:25.320139Z","steps":["trace[2111490591] 'process raft request' (duration: 105.779597ms)","trace[2111490591] 'compare' (duration: 117.936166ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:25.320272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.25031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-01T08:17:25.320313Z","caller":"traceutil/trace.go:171","msg":"trace[1086604889] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25442; }","duration":"212.324542ms","start":"2026-07-01T08:17:25.107978Z","end":"2026-07-01T08:17:25.320302Z","steps":["trace[1086604889] 'agreement among raft nodes before linearized reading' (duration: 212.169619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:25.320354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.739817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-07-01T08:17:25.320401Z","caller":"traceutil/trace.go:171","msg":"trace[1382958899] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25442; }","duration":"156.8589ms","start":"2026-07-01T08:17:25.163529Z","end":"2026-07-01T08:17:25.320388Z","steps":["trace[1382958899] 'agreement among raft nodes before linearized reading' (duration: 156.666926ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:25.823042Z","caller":"traceutil/trace.go:171","msg":"trace[2146448222] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"295.204052ms","start":"2026-07-01T08:17:25.527809Z","end":"2026-07-01T08:17:25.823013Z","steps":["trace[2146448222] 'process raft request' (duration: 295.017288ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:25.995194Z","caller":"traceutil/trace.go:171","msg":"trace[1571279568] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"146.499528ms","start":"2026-07-01T08:17:25.848653Z","end":"2026-07-01T08:17:25.995152Z","steps":["trace[1571279568] 'process raft request' (duration: 132.770958ms)","trace[1571279568] 'compare' (duration: 13.342222ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:26.185602Z","caller":"traceutil/trace.go:171","msg":"trace[2110767869] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"335.402852ms","start":"2026-07-01T08:17:25.850175Z","end":"2026-07-01T08:17:26.185577Z","steps":["trace[2110767869] 'process raft request' (duration: 335.358351ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:26.185643Z","caller":"traceutil/trace.go:171","msg":"trace[701787202] transaction","detail":"{read_only:false; response_revision:25450; number_of_response:1; }","duration":"336.818762ms","start":"2026-07-01T08:17:25.848785Z","end":"2026-07-01T08:17:26.185603Z","steps":["trace[701787202] 'process raft request' (duration: 336.568306ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:26.185718Z","caller":"traceutil/trace.go:171","msg":"trace[919550597] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"336.32939ms","start":"2026-07-01T08:17:25.849352Z","end":"2026-07-01T08:17:26.185681Z","steps":["trace[919550597] 'process raft request' (duration: 336.146927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:26.185746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:25.850163Z","time spent":"335.510624ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:26.185826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:25.848757Z","time spent":"336.954504ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:26.185868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:25.849326Z","time spent":"336.464784ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:26.453306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.929773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:26.453484Z","caller":"traceutil/trace.go:171","msg":"trace[1946727768] transaction","detail":"{read_only:false; response_revision:25454; number_of_response:1; }","duration":"103.697735ms","start":"2026-07-01T08:17:26.349771Z","end":"2026-07-01T08:17:26.453469Z","steps":["trace[1946727768] 'process raft request' (duration: 103.627464ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:26.453586Z","caller":"traceutil/trace.go:171","msg":"trace[1817442302] linearizableReadLoop","detail":"{readStateIndex:26292; appliedIndex:26288; }","duration":"452.335977ms","start":"2026-07-01T08:17:26.001219Z","end":"2026-07-01T08:17:26.453555Z","steps":["trace[1817442302] 'read index received' (duration: 184.126506ms)","trace[1817442302] 'applied index is now lower than readState.Index' (duration: 268.205931ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:26.453719Z","caller":"traceutil/trace.go:171","msg":"trace[1290026896] transaction","detail":"{read_only:false; response_revision:25453; number_of_response:1; }","duration":"465.560187ms","start":"2026-07-01T08:17:25.988145Z","end":"2026-07-01T08:17:26.453705Z","steps":["trace[1290026896] 'process raft request' (duration: 327.162214ms)","trace[1290026896] 'compare' (duration: 137.816241ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:26.453789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.016473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:26.453822Z","caller":"traceutil/trace.go:171","msg":"trace[2010654800] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25454; }","duration":"258.091315ms","start":"2026-07-01T08:17:26.195722Z","end":"2026-07-01T08:17:26.453813Z","steps":["trace[2010654800] 'agreement among raft nodes before linearized reading' (duration: 258.029464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:26.453804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.576451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T08:17:26.453825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.402261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-01T08:17:26.453831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:25.988116Z","time spent":"465.637839ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T08:17:26.453858Z","caller":"traceutil/trace.go:171","msg":"trace[746121687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25454; }","duration":"233.465432ms","start":"2026-07-01T08:17:26.220382Z","end":"2026-07-01T08:17:26.453848Z","steps":["trace[746121687] 'agreement among raft nodes before linearized reading' (duration: 233.40506ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:26.453862Z","caller":"traceutil/trace.go:171","msg":"trace[245824479] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25454; }","duration":"452.681844ms","start":"2026-07-01T08:17:26.001163Z","end":"2026-07-01T08:17:26.453845Z","steps":["trace[245824479] 'agreement among raft nodes before linearized reading' (duration: 452.573941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:26.453894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:26.001139Z","time spent":"452.747554ms","remote":"127.0.0.1:57908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-01T08:17:26.454132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.836698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-07-01T08:17:26.454208Z","caller":"traceutil/trace.go:171","msg":"trace[778768267] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25454; }","duration":"180.954001ms","start":"2026-07-01T08:17:26.273236Z","end":"2026-07-01T08:17:26.45419Z","steps":["trace[778768267] 'agreement among raft nodes before linearized reading' (duration: 180.514072ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.005413Z","caller":"traceutil/trace.go:171","msg":"trace[454827186] linearizableReadLoop","detail":"{readStateIndex:26346; appliedIndex:26345; }","duration":"228.753977ms","start":"2026-07-01T08:17:31.776636Z","end":"2026-07-01T08:17:32.00539Z","steps":["trace[454827186] 'read index received' (duration: 228.584583ms)","trace[454827186] 'applied index is now lower than readState.Index' (duration: 168.014µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:32.005491Z","caller":"traceutil/trace.go:171","msg":"trace[471543759] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"230.603474ms","start":"2026-07-01T08:17:31.77484Z","end":"2026-07-01T08:17:32.005444Z","steps":["trace[471543759] 'process raft request' (duration: 230.38252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:32.005635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.981601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f9b34057-a6d7-469e-985d-480b8c05da76\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-01T08:17:32.005682Z","caller":"traceutil/trace.go:171","msg":"trace[1628956559] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f9b34057-a6d7-469e-985d-480b8c05da76; range_end:; response_count:1; response_revision:25505; }","duration":"229.064233ms","start":"2026-07-01T08:17:31.776602Z","end":"2026-07-01T08:17:32.005666Z","steps":["trace[1628956559] 'agreement among raft nodes before linearized reading' (duration: 228.89226ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.10078Z","caller":"traceutil/trace.go:171","msg":"trace[892599912] transaction","detail":"{read_only:false; response_revision:25508; number_of_response:1; }","duration":"229.855999ms","start":"2026-07-01T08:17:31.870897Z","end":"2026-07-01T08:17:32.100753Z","steps":["trace[892599912] 'process raft request' (duration: 229.815278ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.100835Z","caller":"traceutil/trace.go:171","msg":"trace[110315053] transaction","detail":"{read_only:false; response_revision:25507; number_of_response:1; }","duration":"231.077563ms","start":"2026-07-01T08:17:31.869721Z","end":"2026-07-01T08:17:32.100799Z","steps":["trace[110315053] 'process raft request' (duration: 230.94221ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.100787Z","caller":"traceutil/trace.go:171","msg":"trace[1242724365] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"231.317238ms","start":"2026-07-01T08:17:31.869445Z","end":"2026-07-01T08:17:32.100762Z","steps":["trace[1242724365] 'process raft request' (duration: 231.061803ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.130732Z","caller":"traceutil/trace.go:171","msg":"trace[2082590116] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"112.387072ms","start":"2026-07-01T08:17:32.01832Z","end":"2026-07-01T08:17:32.130707Z","steps":["trace[2082590116] 'process raft request' (duration: 112.176168ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.130882Z","caller":"traceutil/trace.go:171","msg":"trace[1346322939] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"112.549126ms","start":"2026-07-01T08:17:32.018314Z","end":"2026-07-01T08:17:32.130863Z","steps":["trace[1346322939] 'process raft request' (duration: 112.344061ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.131131Z","caller":"traceutil/trace.go:171","msg":"trace[1819291417] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"112.7604ms","start":"2026-07-01T08:17:32.018346Z","end":"2026-07-01T08:17:32.131106Z","steps":["trace[1819291417] 'process raft request' (duration: 112.479805ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.131186Z","caller":"traceutil/trace.go:171","msg":"trace[1703181768] linearizableReadLoop","detail":"{readStateIndex:26353; appliedIndex:26346; }","duration":"111.039056ms","start":"2026-07-01T08:17:32.020136Z","end":"2026-07-01T08:17:32.131175Z","steps":["trace[1703181768] 'read index received' (duration: 80.473422ms)","trace[1703181768] 'applied index is now lower than readState.Index' (duration: 30.564904ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:32.131295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.151568ms","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-01T08:17:32.131304Z","caller":"traceutil/trace.go:171","msg":"trace[1834834000] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"112.891883ms","start":"2026-07-01T08:17:32.018398Z","end":"2026-07-01T08:17:32.13129Z","steps":["trace[1834834000] 'process raft request' (duration: 112.616208ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:32.131328Z","caller":"traceutil/trace.go:171","msg":"trace[953672939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25512; }","duration":"111.22059ms","start":"2026-07-01T08:17:32.020099Z","end":"2026-07-01T08:17:32.13132Z","steps":["trace[953672939] 'agreement among raft nodes before linearized reading' (duration: 111.109128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:56.671052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.258199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:17:56.67123Z","caller":"traceutil/trace.go:171","msg":"trace[1683581874] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"117.64463ms","start":"2026-07-01T08:17:56.553567Z","end":"2026-07-01T08:17:56.671212Z","steps":["trace[1683581874] 'process raft request' (duration: 117.587109ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:56.671374Z","caller":"traceutil/trace.go:171","msg":"trace[265024593] linearizableReadLoop","detail":"{readStateIndex:26560; appliedIndex:26559; }","duration":"262.322602ms","start":"2026-07-01T08:17:56.409033Z","end":"2026-07-01T08:17:56.671356Z","steps":["trace[265024593] 'read index received' (duration: 52.627204ms)","trace[265024593] 'applied index is now lower than readState.Index' (duration: 209.693818ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:56.671378Z","caller":"traceutil/trace.go:171","msg":"trace[1350147847] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"309.146447ms","start":"2026-07-01T08:17:56.362204Z","end":"2026-07-01T08:17:56.671351Z","steps":["trace[1350147847] 'process raft request' (duration: 99.50563ms)","trace[1350147847] 'compare' (duration: 209.152287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:56.671513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:56.362187Z","time spent":"309.262099ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T08:17:56.671622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.541486ms","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-01T08:17:56.671707Z","caller":"traceutil/trace.go:171","msg":"trace[1199111256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25714; }","duration":"262.72882ms","start":"2026-07-01T08:17:56.408961Z","end":"2026-07-01T08:17:56.67169Z","steps":["trace[1199111256] 'agreement among raft nodes before linearized reading' (duration: 262.464635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:56.733772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.06487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-01T08:17:56.73386Z","caller":"traceutil/trace.go:171","msg":"trace[1213466875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25715; }","duration":"322.184193ms","start":"2026-07-01T08:17:56.411654Z","end":"2026-07-01T08:17:56.733838Z","steps":["trace[1213466875] 'agreement among raft nodes before linearized reading' (duration: 321.923008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:56.733905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T08:17:56.411643Z","time spent":"322.253074ms","remote":"127.0.0.1:52442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T08:17:56.733953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.369158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:17:56.734044Z","caller":"traceutil/trace.go:171","msg":"trace[1145246225] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25715; }","duration":"202.531822ms","start":"2026-07-01T08:17:56.531492Z","end":"2026-07-01T08:17:56.734024Z","steps":["trace[1145246225] 'agreement among raft nodes before linearized reading' (duration: 202.358478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:56.734425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.552146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-01T08:17:56.734529Z","caller":"traceutil/trace.go:171","msg":"trace[376361324] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25715; }","duration":"261.705149ms","start":"2026-07-01T08:17:56.472798Z","end":"2026-07-01T08:17:56.734503Z","steps":["trace[376361324] 'agreement among raft nodes before linearized reading' (duration: 261.30912ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:56.735041Z","caller":"traceutil/trace.go:171","msg":"trace[1501348860] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"181.213667ms","start":"2026-07-01T08:17:56.553805Z","end":"2026-07-01T08:17:56.735019Z","steps":["trace[1501348860] 'process raft request' (duration: 179.611394ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:56.846998Z","caller":"traceutil/trace.go:171","msg":"trace[1155148228] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"106.85672ms","start":"2026-07-01T08:17:56.7401Z","end":"2026-07-01T08:17:56.846957Z","steps":["trace[1155148228] 'process raft request' (duration: 106.800959ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:56.847068Z","caller":"traceutil/trace.go:171","msg":"trace[562808829] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"171.540598ms","start":"2026-07-01T08:17:56.675496Z","end":"2026-07-01T08:17:56.847037Z","steps":["trace[562808829] 'process raft request' (duration: 136.300519ms)","trace[562808829] 'compare' (duration: 34.900623ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:57.713491Z","caller":"traceutil/trace.go:171","msg":"trace[848160305] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"140.827213ms","start":"2026-07-01T08:17:57.572633Z","end":"2026-07-01T08:17:57.713461Z","steps":["trace[848160305] 'process raft request' (duration: 99.585931ms)","trace[848160305] 'compare' (duration: 41.077199ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:58.541259Z","caller":"traceutil/trace.go:171","msg":"trace[2146318508] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"167.300243ms","start":"2026-07-01T08:17:58.373934Z","end":"2026-07-01T08:17:58.541234Z","steps":["trace[2146318508] 'process raft request' (duration: 167.258772ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:58.541396Z","caller":"traceutil/trace.go:171","msg":"trace[725618493] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"262.641959ms","start":"2026-07-01T08:17:58.278701Z","end":"2026-07-01T08:17:58.541343Z","steps":["trace[725618493] 'process raft request' (duration: 179.650415ms)","trace[725618493] 'compare' (duration: 82.657486ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:17:58.54151Z","caller":"traceutil/trace.go:171","msg":"trace[1738284858] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"262.807622ms","start":"2026-07-01T08:17:58.278691Z","end":"2026-07-01T08:17:58.541499Z","steps":["trace[1738284858] 'process raft request' (duration: 262.448603ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:17:58.541595Z","caller":"traceutil/trace.go:171","msg":"trace[371622308] linearizableReadLoop","detail":"{readStateIndex:26571; appliedIndex:26569; }","duration":"184.631566ms","start":"2026-07-01T08:17:58.356955Z","end":"2026-07-01T08:17:58.541586Z","steps":["trace[371622308] 'read index received' (duration: 101.47272ms)","trace[371622308] 'applied index is now lower than readState.Index' (duration: 83.158016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:17:58.541765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.79327ms","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-01T08:17:58.541794Z","caller":"traceutil/trace.go:171","msg":"trace[589500433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25725; }","duration":"184.869051ms","start":"2026-07-01T08:17:58.356917Z","end":"2026-07-01T08:17:58.541786Z","steps":["trace[589500433] 'agreement among raft nodes before linearized reading' (duration: 184.706398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:17:59.577225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.135114ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-01T08:17:59.710267Z","caller":"traceutil/trace.go:171","msg":"trace[1745690089] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"128.826768ms","start":"2026-07-01T08:17:59.581406Z","end":"2026-07-01T08:17:59.710233Z","steps":["trace[1745690089] 'process raft request' (duration: 128.612213ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:00.713999Z","caller":"traceutil/trace.go:171","msg":"trace[482172291] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"166.104598ms","start":"2026-07-01T08:18:00.547864Z","end":"2026-07-01T08:18:00.713969Z","steps":["trace[482172291] 'process raft request' (duration: 165.885494ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:00.901055Z","caller":"traceutil/trace.go:171","msg":"trace[1717017107] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"219.748764ms","start":"2026-07-01T08:18:00.681248Z","end":"2026-07-01T08:18:00.900997Z","steps":["trace[1717017107] 'process raft request' (duration: 146.841346ms)","trace[1717017107] 'compare' (duration: 72.622842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:18:00.901237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.328587ms","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-01T08:18:00.901276Z","caller":"traceutil/trace.go:171","msg":"trace[1420221578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25740; }","duration":"277.387338ms","start":"2026-07-01T08:18:00.623881Z","end":"2026-07-01T08:18:00.901269Z","steps":["trace[1420221578] 'agreement among raft nodes before linearized reading' (duration: 277.272836ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:00.901085Z","caller":"traceutil/trace.go:171","msg":"trace[1197433201] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"151.247345ms","start":"2026-07-01T08:18:00.749821Z","end":"2026-07-01T08:18:00.901068Z","steps":["trace[1197433201] 'process raft request' (duration: 151.118742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:00.90128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.370414ms","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-01T08:18:00.901103Z","caller":"traceutil/trace.go:171","msg":"trace[917861844] linearizableReadLoop","detail":"{readStateIndex:26587; appliedIndex:26586; }","duration":"187.189048ms","start":"2026-07-01T08:18:00.713895Z","end":"2026-07-01T08:18:00.901084Z","steps":["trace[917861844] 'read index received' (duration: 114.162008ms)","trace[917861844] 'applied index is now lower than readState.Index' (duration: 73.02537ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:18:00.901372Z","caller":"traceutil/trace.go:171","msg":"trace[60115494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25740; }","duration":"216.472856ms","start":"2026-07-01T08:18:00.684882Z","end":"2026-07-01T08:18:00.901355Z","steps":["trace[60115494] 'agreement among raft nodes before linearized reading' (duration: 216.242241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:00.901563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.689677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-01T08:18:00.901631Z","caller":"traceutil/trace.go:171","msg":"trace[1433431135] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25740; }","duration":"183.78368ms","start":"2026-07-01T08:18:00.717831Z","end":"2026-07-01T08:18:00.901614Z","steps":["trace[1433431135] 'agreement among raft nodes before linearized reading' (duration: 183.590386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:00.901699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.864073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:18:00.90183Z","caller":"traceutil/trace.go:171","msg":"trace[589425694] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25740; }","duration":"167.118019ms","start":"2026-07-01T08:18:00.734688Z","end":"2026-07-01T08:18:00.901806Z","steps":["trace[589425694] 'agreement among raft nodes before linearized reading' (duration: 166.843363ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:01.342868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22294} {"level":"info","ts":"2026-07-01T08:18:01.342895Z","caller":"traceutil/trace.go:171","msg":"trace[148820926] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"142.600259ms","start":"2026-07-01T08:18:01.200272Z","end":"2026-07-01T08:18:01.342872Z","steps":["trace[148820926] 'process raft request' (duration: 142.413695ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:01.342907Z","caller":"traceutil/trace.go:171","msg":"trace[1421081881] compact","detail":"{revision:22294; response_revision:25746; }","duration":"179.87234ms","start":"2026-07-01T08:18:01.163013Z","end":"2026-07-01T08:18:01.342886Z","steps":["trace[1421081881] 'process raft request' (duration: 89.457185ms)","trace[1421081881] 'check and update compact revision' (duration: 90.093007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:18:01.343029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.217973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:18:01.343081Z","caller":"traceutil/trace.go:171","msg":"trace[1391677143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25746; }","duration":"121.316635ms","start":"2026-07-01T08:18:01.22175Z","end":"2026-07-01T08:18:01.343067Z","steps":["trace[1391677143] 'agreement among raft nodes before linearized reading' (duration: 121.205923ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:01.343064Z","caller":"traceutil/trace.go:171","msg":"trace[295249410] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26593; }","duration":"121.06995ms","start":"2026-07-01T08:18:01.2218Z","end":"2026-07-01T08:18:01.34287Z","steps":["trace[295249410] 'read index received' (duration: 30.759317ms)","trace[295249410] 'applied index is now lower than readState.Index' (duration: 90.306993ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T08:18:01.462523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.4502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:18:01.462641Z","caller":"traceutil/trace.go:171","msg":"trace[820262834] linearizableReadLoop","detail":"{readStateIndex:26596; appliedIndex:26595; }","duration":"118.516328ms","start":"2026-07-01T08:18:01.34411Z","end":"2026-07-01T08:18:01.462627Z","steps":["trace[820262834] 'read index received' (duration: 14.843283ms)","trace[820262834] 'applied index is now lower than readState.Index' (duration: 103.672075ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:18:01.462711Z","caller":"traceutil/trace.go:171","msg":"trace[130692505] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"161.556575ms","start":"2026-07-01T08:18:01.301142Z","end":"2026-07-01T08:18:01.462699Z","steps":["trace[130692505] 'process raft request' (duration: 57.86226ms)","trace[130692505] 'compare' (duration: 68.384396ms)","trace[130692505] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; req_size:605; } (duration: 34.800459ms)"],"step_count":3} {"level":"warn","ts":"2026-07-01T08:18:01.462787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.707355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:18:01.462858Z","caller":"traceutil/trace.go:171","msg":"trace[1462641115] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25747; }","duration":"105.810638ms","start":"2026-07-01T08:18:01.357025Z","end":"2026-07-01T08:18:01.462836Z","steps":["trace[1462641115] 'agreement among raft nodes before linearized reading' (duration: 105.693126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:01.462854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.710321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-01T08:18:01.462948Z","caller":"traceutil/trace.go:171","msg":"trace[382453022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25747; }","duration":"118.854194ms","start":"2026-07-01T08:18:01.344071Z","end":"2026-07-01T08:18:01.462925Z","steps":["trace[382453022] 'agreement among raft nodes before linearized reading' (duration: 118.67774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:02.013036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.599041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:18:02.013161Z","caller":"traceutil/trace.go:171","msg":"trace[1629376724] linearizableReadLoop","detail":"{readStateIndex:26598; appliedIndex:26597; }","duration":"223.951818ms","start":"2026-07-01T08:18:01.789191Z","end":"2026-07-01T08:18:02.013142Z","steps":["trace[1629376724] 'read index received' (duration: 3.091743ms)","trace[1629376724] 'applied index is now lower than readState.Index' (duration: 220.858485ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:18:02.013422Z","caller":"traceutil/trace.go:171","msg":"trace[1965062228] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"269.983698ms","start":"2026-07-01T08:18:01.743417Z","end":"2026-07-01T08:18:02.013401Z","steps":["trace[1965062228] 'process raft request' (duration: 48.896618ms)","trace[1965062228] 'compare' (duration: 67.583658ms)","trace[1965062228] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; req_size:536; } (duration: 152.287737ms)"],"step_count":3} {"level":"warn","ts":"2026-07-01T08:18:02.013717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.53384ms","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-01T08:18:02.013764Z","caller":"traceutil/trace.go:171","msg":"trace[2001460335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25749; }","duration":"224.632372ms","start":"2026-07-01T08:18:01.789119Z","end":"2026-07-01T08:18:02.013751Z","steps":["trace[2001460335] 'agreement among raft nodes before linearized reading' (duration: 224.477358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:02.014339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.175316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:274795"} {"level":"info","ts":"2026-07-01T08:18:02.014388Z","caller":"traceutil/trace.go:171","msg":"trace[1774921344] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25749; }","duration":"182.256358ms","start":"2026-07-01T08:18:01.83212Z","end":"2026-07-01T08:18:02.014376Z","steps":["trace[1774921344] 'agreement among raft nodes before linearized reading' (duration: 181.878561ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:02.090598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22294,"took":"744.812374ms","hash":3073709858,"current-db-size-bytes":58519552,"current-db-size":"58 MB","current-db-size-in-use-bytes":45604864,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-07-01T08:18:02.090659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3073709858,"revision":22294,"compact-revision":19952} {"level":"info","ts":"2026-07-01T08:18:03.037813Z","caller":"traceutil/trace.go:171","msg":"trace[637324875] linearizableReadLoop","detail":"{readStateIndex:26605; appliedIndex:26603; }","duration":"120.642711ms","start":"2026-07-01T08:18:02.917148Z","end":"2026-07-01T08:18:03.037791Z","steps":["trace[637324875] 'read index received' (duration: 46.516188ms)","trace[637324875] 'applied index is now lower than readState.Index' (duration: 74.125543ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:18:03.037886Z","caller":"traceutil/trace.go:171","msg":"trace[727552924] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"125.739035ms","start":"2026-07-01T08:18:02.912111Z","end":"2026-07-01T08:18:03.03785Z","steps":["trace[727552924] 'process raft request' (duration: 50.706354ms)","trace[727552924] 'compare' (duration: 74.387597ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:18:03.037941Z","caller":"traceutil/trace.go:171","msg":"trace[892509127] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"125.766996ms","start":"2026-07-01T08:18:02.912162Z","end":"2026-07-01T08:18:03.037929Z","steps":["trace[892509127] 'process raft request' (duration: 125.571382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:03.038357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.189452ms","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-01T08:18:03.038398Z","caller":"traceutil/trace.go:171","msg":"trace[417300883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25756; }","duration":"121.274994ms","start":"2026-07-01T08:18:02.917115Z","end":"2026-07-01T08:18:03.03839Z","steps":["trace[417300883] 'agreement among raft nodes before linearized reading' (duration: 121.131041ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:03.189391Z","caller":"traceutil/trace.go:171","msg":"trace[1709636224] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"142.671731ms","start":"2026-07-01T08:18:03.046693Z","end":"2026-07-01T08:18:03.189365Z","steps":["trace[1709636224] 'process raft request' (duration: 135.347451ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:03.189642Z","caller":"traceutil/trace.go:171","msg":"trace[1705993596] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"142.861465ms","start":"2026-07-01T08:18:03.046742Z","end":"2026-07-01T08:18:03.189604Z","steps":["trace[1705993596] 'process raft request' (duration: 142.528738ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:03.189801Z","caller":"traceutil/trace.go:171","msg":"trace[541985862] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"141.258732ms","start":"2026-07-01T08:18:03.04852Z","end":"2026-07-01T08:18:03.189779Z","steps":["trace[541985862] 'process raft request' (duration: 140.965396ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:08.139861Z","caller":"traceutil/trace.go:171","msg":"trace[1889175369] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"101.037481ms","start":"2026-07-01T08:18:08.038784Z","end":"2026-07-01T08:18:08.139822Z","steps":["trace[1889175369] 'process raft request' (duration: 100.771126ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:18:13.622274Z","caller":"traceutil/trace.go:171","msg":"trace[1593523128] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"123.432128ms","start":"2026-07-01T08:18:13.49882Z","end":"2026-07-01T08:18:13.622253Z","steps":["trace[1593523128] 'process raft request' (duration: 123.306106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T08:18:14.429476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.167832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-01T08:18:14.429649Z","caller":"traceutil/trace.go:171","msg":"trace[1339596454] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"191.626869ms","start":"2026-07-01T08:18:14.238003Z","end":"2026-07-01T08:18:14.42963Z","steps":["trace[1339596454] 'process raft request' (duration: 68.222802ms)","trace[1339596454] 'compare' (duration: 122.930448ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T08:18:36.860492Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000690c.wal"} {"level":"info","ts":"2026-07-01T08:22:13.264498Z","caller":"traceutil/trace.go:171","msg":"trace[1845735750] transaction","detail":"{read_only:false; response_revision:28260; number_of_response:1; }","duration":"161.499235ms","start":"2026-07-01T08:22:13.10297Z","end":"2026-07-01T08:22:13.264469Z","steps":["trace[1845735750] 'process raft request' (duration: 160.977184ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T08:23:01.35262Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25745} {"level":"info","ts":"2026-07-01T08:23:01.513101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25745,"took":"155.558806ms","hash":2105853707,"current-db-size-bytes":60207104,"current-db-size":"60 MB","current-db-size-in-use-bytes":36487168,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-01T08:23:01.513195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2105853707,"revision":25745,"compact-revision":22294} {"level":"info","ts":"2026-07-01T08:24:02.00836Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T08:24:02.013899Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-01T08:24:02.014096Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-01T08:28:01.360017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28623} {"level":"info","ts":"2026-07-01T08:28:01.494973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28623,"took":"130.76985ms","hash":1279639214,"current-db-size-bytes":60207104,"current-db-size":"60 MB","current-db-size-in-use-bytes":30941184,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T08:28:01.495044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1279639214,"revision":28623,"compact-revision":25745} {"level":"info","ts":"2026-07-01T08:33:01.372911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30847} {"level":"info","ts":"2026-07-01T08:33:01.494669Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30847,"took":"118.054371ms","hash":2325559866,"current-db-size-bytes":60207104,"current-db-size":"60 MB","current-db-size-in-use-bytes":31358976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T08:33:01.49473Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2325559866,"revision":30847,"compact-revision":28623} {"level":"info","ts":"2026-07-01T08:38:01.378714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33111} {"level":"info","ts":"2026-07-01T08:38:01.523832Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33111,"took":"140.726964ms","hash":1076578049,"current-db-size-bytes":61247488,"current-db-size":"61 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T08:38:01.523901Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1076578049,"revision":33111,"compact-revision":30847} {"level":"info","ts":"2026-07-01T08:43:01.386149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35409} {"level":"info","ts":"2026-07-01T08:43:01.547167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35409,"took":"157.022487ms","hash":3749533270,"current-db-size-bytes":61247488,"current-db-size":"61 MB","current-db-size-in-use-bytes":31305728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-01T08:43:01.547235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3749533270,"revision":35409,"compact-revision":33111} {"level":"info","ts":"2026-07-01T08:45:28.321312Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-01T08:45:28.325593Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-01T08:45:28.32575Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-01T08:48:01.392915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37633} {"level":"info","ts":"2026-07-01T08:48:01.505126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37633,"took":"108.098176ms","hash":3206031529,"current-db-size-bytes":61247488,"current-db-size":"61 MB","current-db-size-in-use-bytes":32002048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-01T08:48:01.505182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3206031529,"revision":37633,"compact-revision":35409}