{"level":"warn","ts":"2026-05-30T01:46:45.514713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T01:46:45.514814Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-05-30T01:46:45.514898Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-30T01:46:45.514906Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-30T01:46:45.514923Z","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-05-30T01:46:45.516021Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-05-30T01:46:45.516128Z","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":8,"max-cpu-available":8,"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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-05-30T01:46:45.537496Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.101479ms"} {"level":"info","ts":"2026-05-30T01:46:46.384887Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-05-30T01:46:46.385069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-05-30T01:46:46.385113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-05-30T01:46:46.385125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-30T01:46:46.385136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-05-30T01:46:46.385202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-05-30T01:46:46.396733Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-30T01:46:46.400373Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-30T01:46:46.403924Z","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-05-30T01:46:46.406604Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-30T01:46:46.407429Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-30T01:46:46.407552Z","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-05-30T01:46:46.407602Z","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-05-30T01:46:46.407611Z","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-05-30T01:46:46.410466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-05-30T01:46:46.410904Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-05-30T01:46:46.41496Z","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-05-30T01:46:46.415196Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-30T01:46:46.415232Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-30T01:46:46.415306Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-30T01:46:46.415319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-05-30T01:46:47.088705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-05-30T01:46:47.088766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-30T01:46:47.088813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-05-30T01:46:47.088834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-05-30T01:46:47.088846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-30T01:46:47.088886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-05-30T01:46:47.088901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-05-30T01:46:47.099011Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-05-30T01:46:47.099167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:46:47.099108Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:47.099459Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-30T01:46:47.10033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:47.100455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:47.100488Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-30T01:46:47.101915Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-30T01:46:47.10218Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-30T01:46:47.102224Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-30T01:46:47.10411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-05-30T01:46:53.135643Z","caller":"traceutil/trace.go:171","msg":"trace[265451437] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"125.465424ms","start":"2026-05-30T01:46:53.010118Z","end":"2026-05-30T01:46:53.135583Z","steps":["trace[265451437] 'process raft request' (duration: 107.452103ms)","trace[265451437] 'compare' (duration: 17.699607ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:46:55.833372Z","caller":"traceutil/trace.go:171","msg":"trace[1805573214] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"124.003093ms","start":"2026-05-30T01:46:55.709332Z","end":"2026-05-30T01:46:55.833335Z","steps":["trace[1805573214] 'read index received' (duration: 102.038287ms)","trace[1805573214] 'applied index is now lower than readState.Index' (duration: 21.963726ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:46:55.833388Z","caller":"traceutil/trace.go:171","msg":"trace[302708218] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"126.001119ms","start":"2026-05-30T01:46:55.707367Z","end":"2026-05-30T01:46:55.833368Z","steps":["trace[302708218] 'process raft request' (duration: 103.989852ms)","trace[302708218] 'compare' (duration: 21.807614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:46:55.833635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.279906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-30T01:46:55.833706Z","caller":"traceutil/trace.go:171","msg":"trace[1847494688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:321; }","duration":"124.406049ms","start":"2026-05-30T01:46:55.70929Z","end":"2026-05-30T01:46:55.833696Z","steps":["trace[1847494688] 'agreement among raft nodes before linearized reading' (duration: 124.172456ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:46:55.83947Z","caller":"traceutil/trace.go:171","msg":"trace[1744035750] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"127.019881ms","start":"2026-05-30T01:46:55.712331Z","end":"2026-05-30T01:46:55.83935Z","steps":["trace[1744035750] 'process raft request' (duration: 126.804328ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:31.753632Z","caller":"traceutil/trace.go:171","msg":"trace[80415423] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"277.709769ms","start":"2026-05-30T01:47:31.475893Z","end":"2026-05-30T01:47:31.753603Z","steps":["trace[80415423] 'process raft request' (duration: 277.473597ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:31.940977Z","caller":"traceutil/trace.go:171","msg":"trace[86736568] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"127.381097ms","start":"2026-05-30T01:47:31.813558Z","end":"2026-05-30T01:47:31.940939Z","steps":["trace[86736568] 'process raft request' (duration: 127.038494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:32.901116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.1994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:47:32.90142Z","caller":"traceutil/trace.go:171","msg":"trace[1292601123] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"724.777802ms","start":"2026-05-30T01:47:32.17659Z","end":"2026-05-30T01:47:32.901368Z","steps":["trace[1292601123] 'process raft request' (duration: 596.679693ms)","trace[1292601123] 'compare' (duration: 126.081919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:47:32.902199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:32.176562Z","time spent":"724.948474ms","remote":"127.0.0.1:51660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:32.902431Z","caller":"traceutil/trace.go:171","msg":"trace[394354030] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"284.693408ms","start":"2026-05-30T01:47:32.617691Z","end":"2026-05-30T01:47:32.902384Z","steps":["trace[394354030] 'process raft request' (duration: 283.573867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:32.902708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.681981ms","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-05-30T01:47:32.90286Z","caller":"traceutil/trace.go:171","msg":"trace[1528247468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"151.886852ms","start":"2026-05-30T01:47:32.750955Z","end":"2026-05-30T01:47:32.902842Z","steps":["trace[1528247468] 'agreement among raft nodes before linearized reading' (duration: 151.098055ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:32.906282Z","caller":"traceutil/trace.go:171","msg":"trace[1492625300] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:610; }","duration":"150.488879ms","start":"2026-05-30T01:47:32.751014Z","end":"2026-05-30T01:47:32.901503Z","steps":["trace[1492625300] 'read index received' (duration: 21.919546ms)","trace[1492625300] 'applied index is now lower than readState.Index' (duration: 128.568303ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:47:32.90691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.914947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T01:47:32.906998Z","caller":"traceutil/trace.go:171","msg":"trace[1161128413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"123.040339ms","start":"2026-05-30T01:47:32.783922Z","end":"2026-05-30T01:47:32.906963Z","steps":["trace[1161128413] 'agreement among raft nodes before linearized reading' (duration: 122.816907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:34.01181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.753658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:47:34.012225Z","caller":"traceutil/trace.go:171","msg":"trace[1284421164] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.100392823s","start":"2026-05-30T01:47:32.911805Z","end":"2026-05-30T01:47:34.012198Z","steps":["trace[1284421164] 'process raft request' (duration: 446.164481ms)","trace[1284421164] 'compare' (duration: 653.159562ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:34.01237Z","caller":"traceutil/trace.go:171","msg":"trace[497305731] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.098989889s","start":"2026-05-30T01:47:32.913336Z","end":"2026-05-30T01:47:34.012326Z","steps":["trace[497305731] 'process raft request' (duration: 1.098641566s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:47:34.012437Z","caller":"traceutil/trace.go:171","msg":"trace[390369652] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.042178531s","start":"2026-05-30T01:47:32.970227Z","end":"2026-05-30T01:47:34.012405Z","steps":["trace[390369652] 'process raft request' (duration: 1.041951859s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:34.012604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050602584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-30T01:47:34.012626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:32.970205Z","time spent":"1.042353062s","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:47:34.012667Z","caller":"traceutil/trace.go:171","msg":"trace[263741340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:598; }","duration":"1.050709314s","start":"2026-05-30T01:47:32.96194Z","end":"2026-05-30T01:47:34.012649Z","steps":["trace[263741340] 'agreement among raft nodes before linearized reading' (duration: 1.050497072s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:34.012707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:32.961907Z","time spent":"1.050792236s","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-30T01:47:34.012602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:32.913329Z","time spent":"1.099147501s","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:47:34.01237Z","caller":"traceutil/trace.go:171","msg":"trace[1036929914] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"1.050342431s","start":"2026-05-30T01:47:32.961994Z","end":"2026-05-30T01:47:34.012336Z","steps":["trace[1036929914] 'read index received' (duration: 395.992538ms)","trace[1036929914] 'applied index is now lower than readState.Index' (duration: 654.348833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:47:34.012936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02493161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:47:34.012982Z","caller":"traceutil/trace.go:171","msg":"trace[2015913439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"1.024991221s","start":"2026-05-30T01:47:32.987975Z","end":"2026-05-30T01:47:34.012966Z","steps":["trace[2015913439] 'agreement among raft nodes before linearized reading' (duration: 1.024927991s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:34.013018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:32.987964Z","time spent":"1.025047702s","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T01:47:34.013156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:32.911778Z","time spent":"1.100498684s","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:47:34.01327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.942045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-30T01:47:34.013325Z","caller":"traceutil/trace.go:171","msg":"trace[1196109867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"958.029665ms","start":"2026-05-30T01:47:33.055281Z","end":"2026-05-30T01:47:34.01331Z","steps":["trace[1196109867] 'agreement among raft nodes before linearized reading' (duration: 957.889213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:34.013382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:33.055256Z","time spent":"958.114127ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T01:47:35.344619Z","caller":"traceutil/trace.go:171","msg":"trace[920467227] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"365.355738ms","start":"2026-05-30T01:47:34.979149Z","end":"2026-05-30T01:47:35.344505Z","steps":["trace[920467227] 'process raft request' (duration: 364.551231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:35.344825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:34.979103Z","time spent":"365.617992ms","remote":"127.0.0.1:51660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:47:49.489942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970820309514857,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-30T01:47:49.574413Z","caller":"traceutil/trace.go:171","msg":"trace[319350132] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"584.770393ms","start":"2026-05-30T01:47:48.98961Z","end":"2026-05-30T01:47:49.57438Z","steps":["trace[319350132] 'read index received' (duration: 584.62055ms)","trace[319350132] 'applied index is now lower than readState.Index' (duration: 148.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:47:49.574616Z","caller":"traceutil/trace.go:171","msg":"trace[862917900] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"654.320082ms","start":"2026-05-30T01:47:48.920251Z","end":"2026-05-30T01:47:49.574571Z","steps":["trace[862917900] 'process raft request' (duration: 653.927045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:49.574796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.926913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:47:49.574859Z","caller":"traceutil/trace.go:171","msg":"trace[1980981334] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:764; }","duration":"196.085867ms","start":"2026-05-30T01:47:49.378761Z","end":"2026-05-30T01:47:49.574847Z","steps":["trace[1980981334] 'agreement among raft nodes before linearized reading' (duration: 195.913743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:49.574873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.189291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:47:49.575081Z","caller":"traceutil/trace.go:171","msg":"trace[610400469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"585.455996ms","start":"2026-05-30T01:47:48.989562Z","end":"2026-05-30T01:47:49.575018Z","steps":["trace[610400469] 'agreement among raft nodes before linearized reading' (duration: 585.068928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:47:49.575175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:48.989539Z","time spent":"585.623008ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T01:47:49.574906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:47:48.920189Z","time spent":"654.566756ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:48:53.80139Z","caller":"traceutil/trace.go:171","msg":"trace[1944001476] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1546; }","duration":"190.189133ms","start":"2026-05-30T01:48:53.611173Z","end":"2026-05-30T01:48:53.801362Z","steps":["trace[1944001476] 'process raft request' (duration: 190.080301ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:48:54.026831Z","caller":"traceutil/trace.go:171","msg":"trace[817168057] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1547; }","duration":"122.715526ms","start":"2026-05-30T01:48:53.904076Z","end":"2026-05-30T01:48:54.026792Z","steps":["trace[817168057] 'process raft request' (duration: 122.597065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:04.93352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.601089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:49:04.933613Z","caller":"traceutil/trace.go:171","msg":"trace[1157748342] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1749; }","duration":"158.745ms","start":"2026-05-30T01:49:04.774851Z","end":"2026-05-30T01:49:04.933596Z","steps":["trace[1157748342] 'count revisions from in-memory index tree' (duration: 158.531328ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:05.813486Z","caller":"traceutil/trace.go:171","msg":"trace[1660645625] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"111.495118ms","start":"2026-05-30T01:49:05.701972Z","end":"2026-05-30T01:49:05.813467Z","steps":["trace[1660645625] 'process raft request' (duration: 111.342746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.056972Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853970820309517028,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-30T01:49:14.480673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173044432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98596458-6059-4542-9af0-23c18c1ee144\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-30T01:49:14.480762Z","caller":"traceutil/trace.go:171","msg":"trace[1797886886] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98596458-6059-4542-9af0-23c18c1ee144; range_end:; response_count:1; response_revision:1857; }","duration":"1.173186254s","start":"2026-05-30T01:49:13.307555Z","end":"2026-05-30T01:49:14.480741Z","steps":["trace[1797886886] 'range keys from in-memory index tree' (duration: 1.1728729s)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.480803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:13.307534Z","time spent":"1.173259464s","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98596458-6059-4542-9af0-23c18c1ee144\" "} {"level":"warn","ts":"2026-05-30T01:49:14.481316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.541961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:49:14.481884Z","caller":"traceutil/trace.go:171","msg":"trace[51997021] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"976.669885ms","start":"2026-05-30T01:49:13.505144Z","end":"2026-05-30T01:49:14.481813Z","steps":["trace[51997021] 'process raft request' (duration: 610.558067ms)","trace[51997021] 'compare' (duration: 364.834042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:14.482344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:13.50512Z","time spent":"977.11541ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:49:14.496654Z","caller":"traceutil/trace.go:171","msg":"trace[2110294156] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1925; }","duration":"940.347802ms","start":"2026-05-30T01:49:13.556287Z","end":"2026-05-30T01:49:14.496634Z","steps":["trace[2110294156] 'read index received' (duration: 559.428338ms)","trace[2110294156] 'applied index is now lower than readState.Index' (duration: 380.917874ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:49:14.496707Z","caller":"traceutil/trace.go:171","msg":"trace[1830271995] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"560.462148ms","start":"2026-05-30T01:49:13.936211Z","end":"2026-05-30T01:49:14.496673Z","steps":["trace[1830271995] 'process raft request' (duration: 560.273616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.496937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.648695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98596458-6059-4542-9af0-23c18c1ee144\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-30T01:49:14.496988Z","caller":"traceutil/trace.go:171","msg":"trace[417430827] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-98596458-6059-4542-9af0-23c18c1ee144; range_end:; response_count:1; response_revision:1859; }","duration":"940.729306ms","start":"2026-05-30T01:49:13.556246Z","end":"2026-05-30T01:49:14.496975Z","steps":["trace[417430827] 'agreement among raft nodes before linearized reading' (duration: 940.490953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.497032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:13.556207Z","time spent":"940.816437ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-98596458-6059-4542-9af0-23c18c1ee144\" "} {"level":"warn","ts":"2026-05-30T01:49:14.497179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:13.936181Z","time spent":"560.709861ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:49:14.809712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.060417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:49:14.809801Z","caller":"traceutil/trace.go:171","msg":"trace[1407733452] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"313.080909ms","start":"2026-05-30T01:49:14.496707Z","end":"2026-05-30T01:49:14.809788Z","steps":["trace[1407733452] 'read index received' (duration: 186.764959ms)","trace[1407733452] 'applied index is now lower than readState.Index' (duration: 126.31507ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:14.809948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.167993ms","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":"warn","ts":"2026-05-30T01:49:14.809982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.335979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T01:49:14.810005Z","caller":"traceutil/trace.go:171","msg":"trace[2068753039] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1860; }","duration":"699.274245ms","start":"2026-05-30T01:49:14.110717Z","end":"2026-05-30T01:49:14.809991Z","steps":["trace[2068753039] 'agreement among raft nodes before linearized reading' (duration: 699.088963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.810003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.4189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-30T01:49:14.810034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.110695Z","time spent":"699.334165ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-30T01:49:14.810043Z","caller":"traceutil/trace.go:171","msg":"trace[2047208057] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"771.496571ms","start":"2026-05-30T01:49:14.038535Z","end":"2026-05-30T01:49:14.810032Z","steps":["trace[2047208057] 'agreement among raft nodes before linearized reading' (duration: 771.317559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.810087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.284914ms","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-05-30T01:49:14.810075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.038514Z","time spent":"771.552262ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-30T01:49:14.810152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061365062s","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:7"} {"level":"warn","ts":"2026-05-30T01:49:14.810187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.900887ms","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":"warn","ts":"2026-05-30T01:49:14.81023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.579005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-30T01:49:14.810247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.066327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T01:49:14.810238Z","caller":"traceutil/trace.go:171","msg":"trace[1971835245] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1860; }","duration":"1.061508604s","start":"2026-05-30T01:49:13.74871Z","end":"2026-05-30T01:49:14.810218Z","steps":["trace[1971835245] 'agreement among raft nodes before linearized reading' (duration: 1.061342642s)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:14.810255Z","caller":"traceutil/trace.go:171","msg":"trace[1897066097] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1860; }","duration":"511.031078ms","start":"2026-05-30T01:49:14.299206Z","end":"2026-05-30T01:49:14.810237Z","steps":["trace[1897066097] 'agreement among raft nodes before linearized reading' (duration: 510.867106ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:14.81028Z","caller":"traceutil/trace.go:171","msg":"trace[1067450133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1860; }","duration":"116.686516ms","start":"2026-05-30T01:49:14.693582Z","end":"2026-05-30T01:49:14.810269Z","steps":["trace[1067450133] 'agreement among raft nodes before linearized reading' (duration: 116.586485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.810298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.299181Z","time spent":"511.108859ms","remote":"127.0.0.1:36128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-30T01:49:14.810127Z","caller":"traceutil/trace.go:171","msg":"trace[2110794872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"171.374185ms","start":"2026-05-30T01:49:14.638743Z","end":"2026-05-30T01:49:14.810117Z","steps":["trace[2110794872] 'agreement among raft nodes before linearized reading' (duration: 171.286144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:14.810297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:13.748688Z","time spent":"1.061598655s","remote":"127.0.0.1:44052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-30T01:49:14.81028Z","caller":"traceutil/trace.go:171","msg":"trace[257403220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"105.145568ms","start":"2026-05-30T01:49:14.705126Z","end":"2026-05-30T01:49:14.810272Z","steps":["trace[257403220] 'agreement among raft nodes before linearized reading' (duration: 105.040987ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:14.81016Z","caller":"traceutil/trace.go:171","msg":"trace[1571192560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1860; }","duration":"445.551001ms","start":"2026-05-30T01:49:14.364599Z","end":"2026-05-30T01:49:14.81015Z","steps":["trace[1571192560] 'agreement among raft nodes before linearized reading' (duration: 445.194467ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:14.810373Z","caller":"traceutil/trace.go:171","msg":"trace[1746048282] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"321.143914ms","start":"2026-05-30T01:49:14.489221Z","end":"2026-05-30T01:49:14.810364Z","steps":["trace[1746048282] 'process raft request' (duration: 194.353059ms)","trace[1746048282] 'compare' (duration: 125.968646ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:14.810436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.489193Z","time spent":"321.200865ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:49:14.81051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.364578Z","time spent":"445.843224ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-30T01:49:15.513811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.959734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:49:15.51419Z","caller":"traceutil/trace.go:171","msg":"trace[1217642686] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"698.850902ms","start":"2026-05-30T01:49:14.815317Z","end":"2026-05-30T01:49:15.514168Z","steps":["trace[1217642686] 'process raft request' (duration: 698.731451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:15.514301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.815309Z","time spent":"698.940513ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:49:15.514342Z","caller":"traceutil/trace.go:171","msg":"trace[886225194] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"699.55352ms","start":"2026-05-30T01:49:14.814776Z","end":"2026-05-30T01:49:15.514329Z","steps":["trace[886225194] 'process raft request' (duration: 196.980739ms)","trace[886225194] 'compare' (duration: 501.841133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:15.514413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.814764Z","time spent":"699.60579ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:49:15.514439Z","caller":"traceutil/trace.go:171","msg":"trace[899833828] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"697.928221ms","start":"2026-05-30T01:49:14.81649Z","end":"2026-05-30T01:49:15.514419Z","steps":["trace[899833828] 'process raft request' (duration: 697.613767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:15.514583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.816471Z","time spent":"698.035312ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:49:15.51446Z","caller":"traceutil/trace.go:171","msg":"trace[300697239] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"699.521059ms","start":"2026-05-30T01:49:14.814922Z","end":"2026-05-30T01:49:15.514443Z","steps":["trace[300697239] 'process raft request' (duration: 699.077984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:15.514699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.814917Z","time spent":"699.724533ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:49:15.514205Z","caller":"traceutil/trace.go:171","msg":"trace[325697726] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"699.302807ms","start":"2026-05-30T01:49:14.814882Z","end":"2026-05-30T01:49:15.514185Z","steps":["trace[325697726] 'process raft request' (duration: 699.038204ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:15.514863Z","caller":"traceutil/trace.go:171","msg":"trace[247265661] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"669.349752ms","start":"2026-05-30T01:49:14.845505Z","end":"2026-05-30T01:49:15.514855Z","steps":["trace[247265661] 'process raft request' (duration: 668.839065ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:15.514917Z","caller":"traceutil/trace.go:171","msg":"trace[1311500525] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"515.411124ms","start":"2026-05-30T01:49:14.99949Z","end":"2026-05-30T01:49:15.514901Z","steps":["trace[1311500525] 'process raft request' (duration: 515.278382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:15.514929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.845473Z","time spent":"669.423453ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:49:15.515017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.814876Z","time spent":"699.998036ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:49:15.51502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.999467Z","time spent":"515.501434ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:49:15.515061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.918958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:49:15.51509Z","caller":"traceutil/trace.go:171","msg":"trace[113570453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1868; }","duration":"525.98147ms","start":"2026-05-30T01:49:14.9891Z","end":"2026-05-30T01:49:15.515081Z","steps":["trace[113570453] 'agreement among raft nodes before linearized reading' (duration: 525.741627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:15.515124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:14.989079Z","time spent":"526.03952ms","remote":"127.0.0.1:51590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-30T01:49:15.514817Z","caller":"traceutil/trace.go:171","msg":"trace[1293277845] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1929; }","duration":"525.659485ms","start":"2026-05-30T01:49:14.989146Z","end":"2026-05-30T01:49:15.514806Z","steps":["trace[1293277845] 'read index received' (duration: 22.623068ms)","trace[1293277845] 'applied index is now lower than readState.Index' (duration: 503.035577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:17.665398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.626001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-30T01:49:17.665556Z","caller":"traceutil/trace.go:171","msg":"trace[1834275966] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1881; }","duration":"185.0611ms","start":"2026-05-30T01:49:17.480465Z","end":"2026-05-30T01:49:17.665527Z","steps":["trace[1834275966] 'process raft request' (duration: 58.177876ms)","trace[1834275966] 'compare' (duration: 126.494579ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:49:17.665594Z","caller":"traceutil/trace.go:171","msg":"trace[536928287] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"144.38177ms","start":"2026-05-30T01:49:17.521194Z","end":"2026-05-30T01:49:17.665576Z","steps":["trace[536928287] 'process raft request' (duration: 144.29978ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:17.665628Z","caller":"traceutil/trace.go:171","msg":"trace[194516000] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"146.932451ms","start":"2026-05-30T01:49:17.518687Z","end":"2026-05-30T01:49:17.665619Z","steps":["trace[194516000] 'read index received' (duration: 20.037447ms)","trace[194516000] 'applied index is now lower than readState.Index' (duration: 126.894174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:17.666049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.009763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T01:49:17.666121Z","caller":"traceutil/trace.go:171","msg":"trace[534289354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1882; }","duration":"147.129654ms","start":"2026-05-30T01:49:17.518974Z","end":"2026-05-30T01:49:17.666104Z","steps":["trace[534289354] 'agreement among raft nodes before linearized reading' (duration: 146.88646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:17.666234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.078862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T01:49:17.666295Z","caller":"traceutil/trace.go:171","msg":"trace[505741967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1882; }","duration":"146.160763ms","start":"2026-05-30T01:49:17.520117Z","end":"2026-05-30T01:49:17.666278Z","steps":["trace[505741967] 'agreement among raft nodes before linearized reading' (duration: 145.986721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:17.666347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.631988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-30T01:49:17.666403Z","caller":"traceutil/trace.go:171","msg":"trace[2140775389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1882; }","duration":"146.718889ms","start":"2026-05-30T01:49:17.519668Z","end":"2026-05-30T01:49:17.666387Z","steps":["trace[2140775389] 'agreement among raft nodes before linearized reading' (duration: 146.528937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:17.666448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.234615ms","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-05-30T01:49:17.66652Z","caller":"traceutil/trace.go:171","msg":"trace[616219595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1882; }","duration":"147.322816ms","start":"2026-05-30T01:49:17.519182Z","end":"2026-05-30T01:49:17.666505Z","steps":["trace[616219595] 'agreement among raft nodes before linearized reading' (duration: 147.163904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:17.666685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.993064ms","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-05-30T01:49:17.666732Z","caller":"traceutil/trace.go:171","msg":"trace[2053590973] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1882; }","duration":"148.075275ms","start":"2026-05-30T01:49:17.518643Z","end":"2026-05-30T01:49:17.666719Z","steps":["trace[2053590973] 'agreement among raft nodes before linearized reading' (duration: 147.965793ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:44.220306Z","caller":"traceutil/trace.go:171","msg":"trace[1985550732] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"162.479943ms","start":"2026-05-30T01:49:44.0578Z","end":"2026-05-30T01:49:44.22028Z","steps":["trace[1985550732] 'process raft request' (duration: 162.437883ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:44.220334Z","caller":"traceutil/trace.go:171","msg":"trace[747284059] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"247.033933ms","start":"2026-05-30T01:49:43.973267Z","end":"2026-05-30T01:49:44.220301Z","steps":["trace[747284059] 'process raft request' (duration: 201.333675ms)","trace[747284059] 'compare' (duration: 45.437605ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:49:44.220505Z","caller":"traceutil/trace.go:171","msg":"trace[1677910762] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"247.139775ms","start":"2026-05-30T01:49:43.973355Z","end":"2026-05-30T01:49:44.220495Z","steps":["trace[1677910762] 'process raft request' (duration: 246.831931ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:44.220594Z","caller":"traceutil/trace.go:171","msg":"trace[2063842913] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2149; }","duration":"168.409799ms","start":"2026-05-30T01:49:44.052173Z","end":"2026-05-30T01:49:44.220583Z","steps":["trace[2063842913] 'read index received' (duration: 122.428438ms)","trace[2063842913] 'applied index is now lower than readState.Index' (duration: 45.980411ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:44.220785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.558851ms","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-05-30T01:49:44.220841Z","caller":"traceutil/trace.go:171","msg":"trace[1756029678] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2078; }","duration":"249.646001ms","start":"2026-05-30T01:49:43.971177Z","end":"2026-05-30T01:49:44.220823Z","steps":["trace[1756029678] 'agreement among raft nodes before linearized reading' (duration: 249.448259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:44.220958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.218082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-30T01:49:44.221086Z","caller":"traceutil/trace.go:171","msg":"trace[1402689828] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2078; }","duration":"172.414804ms","start":"2026-05-30T01:49:44.04864Z","end":"2026-05-30T01:49:44.221055Z","steps":["trace[1402689828] 'agreement among raft nodes before linearized reading' (duration: 171.992959ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:44.959324Z","caller":"traceutil/trace.go:171","msg":"trace[128235902] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"231.326876ms","start":"2026-05-30T01:49:44.727945Z","end":"2026-05-30T01:49:44.959272Z","steps":["trace[128235902] 'process raft request' (duration: 230.910582ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:44.978951Z","caller":"traceutil/trace.go:171","msg":"trace[221722448] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"249.864283ms","start":"2026-05-30T01:49:44.729039Z","end":"2026-05-30T01:49:44.978904Z","steps":["trace[221722448] 'process raft request' (duration: 249.61943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:45.573535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.691229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-30T01:49:45.573558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.397356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-30T01:49:45.573634Z","caller":"traceutil/trace.go:171","msg":"trace[2113270690] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2081; }","duration":"344.84353ms","start":"2026-05-30T01:49:45.228756Z","end":"2026-05-30T01:49:45.5736Z","steps":["trace[2113270690] 'range keys from in-memory index tree' (duration: 344.488016ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:49:45.57366Z","caller":"traceutil/trace.go:171","msg":"trace[671052154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2081; }","duration":"540.560548ms","start":"2026-05-30T01:49:45.033074Z","end":"2026-05-30T01:49:45.573635Z","steps":["trace[671052154] 'range keys from in-memory index tree' (duration: 540.164764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:45.573684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:45.228725Z","time spent":"344.949401ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-30T01:49:45.573714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:45.033053Z","time spent":"540.647199ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-30T01:49:45.689326Z","caller":"traceutil/trace.go:171","msg":"trace[339806649] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"111.134212ms","start":"2026-05-30T01:49:45.578148Z","end":"2026-05-30T01:49:45.689282Z","steps":["trace[339806649] 'process raft request' (duration: 110.840779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:49:46.284718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.579138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:49:46.284995Z","caller":"traceutil/trace.go:171","msg":"trace[249412254] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"227.440555ms","start":"2026-05-30T01:49:46.057511Z","end":"2026-05-30T01:49:46.284951Z","steps":["trace[249412254] 'read index received' (duration: 26.436982ms)","trace[249412254] 'applied index is now lower than readState.Index' (duration: 200.996903ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:49:46.285307Z","caller":"traceutil/trace.go:171","msg":"trace[2061847208] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"413.584087ms","start":"2026-05-30T01:49:45.871672Z","end":"2026-05-30T01:49:46.285256Z","steps":["trace[2061847208] 'process raft request' (duration: 212.324731ms)","trace[2061847208] 'compare' (duration: 200.395155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:49:46.28551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:49:45.871638Z","time spent":"413.764238ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:49:46.286055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.021932ms","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-05-30T01:49:46.286149Z","caller":"traceutil/trace.go:171","msg":"trace[869970035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2084; }","duration":"228.692579ms","start":"2026-05-30T01:49:46.057437Z","end":"2026-05-30T01:49:46.286129Z","steps":["trace[869970035] 'agreement among raft nodes before linearized reading' (duration: 227.726069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:51:32.012853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.893339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b434dab0618184\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-05-30T01:51:32.012962Z","caller":"traceutil/trace.go:171","msg":"trace[1715562049] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b434dab0618184; range_end:; response_count:1; response_revision:2763; }","duration":"426.051441ms","start":"2026-05-30T01:51:31.586889Z","end":"2026-05-30T01:51:32.012941Z","steps":["trace[1715562049] 'range keys from in-memory index tree' (duration: 425.687117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:51:32.013018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:51:31.586873Z","time spent":"426.130111ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b434dab0618184\" "} {"level":"info","ts":"2026-05-30T01:54:25.053757Z","caller":"traceutil/trace.go:171","msg":"trace[969691926] transaction","detail":"{read_only:false; response_revision:3827; number_of_response:1; }","duration":"110.79342ms","start":"2026-05-30T01:54:24.942923Z","end":"2026-05-30T01:54:25.053717Z","steps":["trace[969691926] 'process raft request' (duration: 97.958716ms)","trace[969691926] 'compare' (duration: 12.425735ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:54:25.053846Z","caller":"traceutil/trace.go:171","msg":"trace[972195135] linearizableReadLoop","detail":"{readStateIndex:3968; appliedIndex:3966; }","duration":"105.274748ms","start":"2026-05-30T01:54:24.948541Z","end":"2026-05-30T01:54:25.053815Z","steps":["trace[972195135] 'read index received' (duration: 8.995434ms)","trace[972195135] 'applied index is now lower than readState.Index' (duration: 96.275273ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:54:25.054576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.018676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-30T01:54:25.054613Z","caller":"traceutil/trace.go:171","msg":"trace[1053336279] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:3829; }","duration":"106.095327ms","start":"2026-05-30T01:54:24.948508Z","end":"2026-05-30T01:54:25.054603Z","steps":["trace[1053336279] 'agreement among raft nodes before linearized reading' (duration: 105.513833ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:54:36.139346Z","caller":"traceutil/trace.go:171","msg":"trace[1073022100] transaction","detail":"{read_only:false; response_revision:3897; number_of_response:1; }","duration":"112.723109ms","start":"2026-05-30T01:54:36.026606Z","end":"2026-05-30T01:54:36.139329Z","steps":["trace[1073022100] 'process raft request' (duration: 112.638937ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:00.295756Z","caller":"traceutil/trace.go:171","msg":"trace[810429771] transaction","detail":"{read_only:false; response_revision:4030; number_of_response:1; }","duration":"112.112121ms","start":"2026-05-30T01:55:00.183623Z","end":"2026-05-30T01:55:00.295735Z","steps":["trace[810429771] 'process raft request' (duration: 110.602029ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:00.566064Z","caller":"traceutil/trace.go:171","msg":"trace[1148673446] transaction","detail":"{read_only:false; response_revision:4032; number_of_response:1; }","duration":"264.910365ms","start":"2026-05-30T01:55:00.301129Z","end":"2026-05-30T01:55:00.56604Z","steps":["trace[1148673446] 'process raft request' (duration: 185.903382ms)","trace[1148673446] 'compare' (duration: 78.846599ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:00.566493Z","caller":"traceutil/trace.go:171","msg":"trace[1580653890] transaction","detail":"{read_only:false; response_revision:4034; number_of_response:1; }","duration":"243.538427ms","start":"2026-05-30T01:55:00.32294Z","end":"2026-05-30T01:55:00.566479Z","steps":["trace[1580653890] 'process raft request' (duration: 243.325712ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:00.566504Z","caller":"traceutil/trace.go:171","msg":"trace[435867820] transaction","detail":"{read_only:false; response_revision:4036; number_of_response:1; }","duration":"239.078941ms","start":"2026-05-30T01:55:00.327405Z","end":"2026-05-30T01:55:00.566484Z","steps":["trace[435867820] 'process raft request' (duration: 239.0212ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:00.566748Z","caller":"traceutil/trace.go:171","msg":"trace[575006403] transaction","detail":"{read_only:false; response_revision:4033; number_of_response:1; }","duration":"257.268591ms","start":"2026-05-30T01:55:00.309468Z","end":"2026-05-30T01:55:00.566737Z","steps":["trace[575006403] 'process raft request' (duration: 256.536316ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:00.566863Z","caller":"traceutil/trace.go:171","msg":"trace[1852382878] transaction","detail":"{read_only:false; response_revision:4035; number_of_response:1; }","duration":"240.206436ms","start":"2026-05-30T01:55:00.32664Z","end":"2026-05-30T01:55:00.566846Z","steps":["trace[1852382878] 'process raft request' (duration: 239.711325ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:08.683206Z","caller":"traceutil/trace.go:171","msg":"trace[1053413859] transaction","detail":"{read_only:false; response_revision:4410; number_of_response:1; }","duration":"104.706305ms","start":"2026-05-30T01:55:08.578458Z","end":"2026-05-30T01:55:08.683164Z","steps":["trace[1053413859] 'process raft request' (duration: 103.025561ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:08.684372Z","caller":"traceutil/trace.go:171","msg":"trace[672379844] transaction","detail":"{read_only:false; response_revision:4411; number_of_response:1; }","duration":"103.059191ms","start":"2026-05-30T01:55:08.581267Z","end":"2026-05-30T01:55:08.684326Z","steps":["trace[672379844] 'process raft request' (duration: 102.840067ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:09.928245Z","caller":"traceutil/trace.go:171","msg":"trace[997912302] transaction","detail":"{read_only:false; response_revision:4446; number_of_response:1; }","duration":"298.469512ms","start":"2026-05-30T01:55:09.629748Z","end":"2026-05-30T01:55:09.928217Z","steps":["trace[997912302] 'process raft request' (duration: 298.33053ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:09.934381Z","caller":"traceutil/trace.go:171","msg":"trace[2144695466] linearizableReadLoop","detail":"{readStateIndex:4613; appliedIndex:4609; }","duration":"112.106395ms","start":"2026-05-30T01:55:09.822252Z","end":"2026-05-30T01:55:09.934358Z","steps":["trace[2144695466] 'read index received' (duration: 105.914755ms)","trace[2144695466] 'applied index is now lower than readState.Index' (duration: 6.19089ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:09.934477Z","caller":"traceutil/trace.go:171","msg":"trace[1695523348] transaction","detail":"{read_only:false; response_revision:4449; number_of_response:1; }","duration":"154.861338ms","start":"2026-05-30T01:55:09.779608Z","end":"2026-05-30T01:55:09.93447Z","steps":["trace[1695523348] 'process raft request' (duration: 154.725645ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:09.93447Z","caller":"traceutil/trace.go:171","msg":"trace[1698776758] transaction","detail":"{read_only:false; response_revision:4447; number_of_response:1; }","duration":"255.122835ms","start":"2026-05-30T01:55:09.679318Z","end":"2026-05-30T01:55:09.934441Z","steps":["trace[1698776758] 'process raft request' (duration: 254.84888ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:09.934498Z","caller":"traceutil/trace.go:171","msg":"trace[946604103] transaction","detail":"{read_only:false; response_revision:4448; number_of_response:1; }","duration":"190.044834ms","start":"2026-05-30T01:55:09.744428Z","end":"2026-05-30T01:55:09.934472Z","steps":["trace[946604103] 'process raft request' (duration: 189.85419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:09.934662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.40262ms","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-05-30T01:55:09.934716Z","caller":"traceutil/trace.go:171","msg":"trace[1617716593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4449; }","duration":"112.514113ms","start":"2026-05-30T01:55:09.822187Z","end":"2026-05-30T01:55:09.934701Z","steps":["trace[1617716593] 'agreement among raft nodes before linearized reading' (duration: 112.339539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:09.93508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.010746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-tls.18b43512dd04284a\" ","response":"range_response_count:1 size:793"} {"level":"info","ts":"2026-05-30T01:55:09.935155Z","caller":"traceutil/trace.go:171","msg":"trace[1255799657] range","detail":"{range_begin:/registry/events/monitoring/prometheus-tls.18b43512dd04284a; range_end:; response_count:1; response_revision:4449; }","duration":"105.144989ms","start":"2026-05-30T01:55:09.829993Z","end":"2026-05-30T01:55:09.935138Z","steps":["trace[1255799657] 'agreement among raft nodes before linearized reading' (duration: 104.559017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:13.201939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.811619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:13.202524Z","caller":"traceutil/trace.go:171","msg":"trace[1913909744] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4600; }","duration":"106.453472ms","start":"2026-05-30T01:55:13.09605Z","end":"2026-05-30T01:55:13.202503Z","steps":["trace[1913909744] 'count revisions from in-memory index tree' (duration: 105.731828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:13.20221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.189084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1433"} {"level":"info","ts":"2026-05-30T01:55:13.202672Z","caller":"traceutil/trace.go:171","msg":"trace[397490600] range","detail":"{range_begin:/registry/rolebindings/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4600; }","duration":"144.707595ms","start":"2026-05-30T01:55:13.057954Z","end":"2026-05-30T01:55:13.202662Z","steps":["trace[397490600] 'range keys from in-memory index tree' (duration: 143.997851ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:15.859058Z","caller":"traceutil/trace.go:171","msg":"trace[1644437538] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"109.590257ms","start":"2026-05-30T01:55:15.749419Z","end":"2026-05-30T01:55:15.859009Z","steps":["trace[1644437538] 'process raft request' (duration: 109.397693ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:18.433337Z","caller":"traceutil/trace.go:171","msg":"trace[1342906167] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"174.844773ms","start":"2026-05-30T01:55:18.258465Z","end":"2026-05-30T01:55:18.43331Z","steps":["trace[1342906167] 'process raft request' (duration: 174.69715ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:18.433379Z","caller":"traceutil/trace.go:171","msg":"trace[148191668] linearizableReadLoop","detail":"{readStateIndex:4876; appliedIndex:4876; }","duration":"129.833243ms","start":"2026-05-30T01:55:18.303518Z","end":"2026-05-30T01:55:18.433351Z","steps":["trace[148191668] 'read index received' (duration: 129.824483ms)","trace[148191668] 'applied index is now lower than readState.Index' (duration: 6.84µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:18.433647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.093988ms","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-05-30T01:55:18.433693Z","caller":"traceutil/trace.go:171","msg":"trace[673380130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4704; }","duration":"130.211871ms","start":"2026-05-30T01:55:18.303468Z","end":"2026-05-30T01:55:18.43368Z","steps":["trace[673380130] 'agreement among raft nodes before linearized reading' (duration: 129.996847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:18.463033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.667019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T01:55:18.463099Z","caller":"traceutil/trace.go:171","msg":"trace[42440863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4705; }","duration":"148.795282ms","start":"2026-05-30T01:55:18.314289Z","end":"2026-05-30T01:55:18.463085Z","steps":["trace[42440863] 'agreement among raft nodes before linearized reading' (duration: 148.622328ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:18.630534Z","caller":"traceutil/trace.go:171","msg":"trace[944726780] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"162.801887ms","start":"2026-05-30T01:55:18.467706Z","end":"2026-05-30T01:55:18.630508Z","steps":["trace[944726780] 'process raft request' (duration: 162.711005ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:18.630696Z","caller":"traceutil/trace.go:171","msg":"trace[1001469060] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"192.961782ms","start":"2026-05-30T01:55:18.43771Z","end":"2026-05-30T01:55:18.630672Z","steps":["trace[1001469060] 'process raft request' (duration: 99.960812ms)","trace[1001469060] 'compare' (duration: 92.457919ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:19.055155Z","caller":"traceutil/trace.go:171","msg":"trace[966233612] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"182.908435ms","start":"2026-05-30T01:55:18.872217Z","end":"2026-05-30T01:55:19.055126Z","steps":["trace[966233612] 'read index received' (duration: 176.175468ms)","trace[966233612] 'applied index is now lower than readState.Index' (duration: 6.731607ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:19.055298Z","caller":"traceutil/trace.go:171","msg":"trace[1332469747] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"183.211051ms","start":"2026-05-30T01:55:18.87207Z","end":"2026-05-30T01:55:19.055281Z","steps":["trace[1332469747] 'process raft request' (duration: 176.30541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:19.056133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.301223ms","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-05-30T01:55:19.056206Z","caller":"traceutil/trace.go:171","msg":"trace[1634961139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4709; }","duration":"183.988826ms","start":"2026-05-30T01:55:18.872197Z","end":"2026-05-30T01:55:19.056186Z","steps":["trace[1634961139] 'agreement among raft nodes before linearized reading' (duration: 183.14474ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:19.195792Z","caller":"traceutil/trace.go:171","msg":"trace[737848811] transaction","detail":"{read_only:false; response_revision:4712; number_of_response:1; }","duration":"126.110771ms","start":"2026-05-30T01:55:19.069647Z","end":"2026-05-30T01:55:19.195758Z","steps":["trace[737848811] 'process raft request' (duration: 125.434337ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:26.189941Z","caller":"traceutil/trace.go:171","msg":"trace[1913644635] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4934; }","duration":"163.60744ms","start":"2026-05-30T01:55:26.026308Z","end":"2026-05-30T01:55:26.189916Z","steps":["trace[1913644635] 'read index received' (duration: 163.459577ms)","trace[1913644635] 'applied index is now lower than readState.Index' (duration: 146.302µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:26.190469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.142ms","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-05-30T01:55:26.190501Z","caller":"traceutil/trace.go:171","msg":"trace[1790341895] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4760; }","duration":"164.195531ms","start":"2026-05-30T01:55:26.026298Z","end":"2026-05-30T01:55:26.190494Z","steps":["trace[1790341895] 'agreement among raft nodes before linearized reading' (duration: 164.050688ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:26.190465Z","caller":"traceutil/trace.go:171","msg":"trace[996053932] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"198.01779ms","start":"2026-05-30T01:55:25.99241Z","end":"2026-05-30T01:55:26.190428Z","steps":["trace[996053932] 'process raft request' (duration: 197.323166ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:26.687733Z","caller":"traceutil/trace.go:171","msg":"trace[331357211] linearizableReadLoop","detail":"{readStateIndex:4936; appliedIndex:4935; }","duration":"492.874187ms","start":"2026-05-30T01:55:26.194793Z","end":"2026-05-30T01:55:26.687667Z","steps":["trace[331357211] 'read index received' (duration: 416.70041ms)","trace[331357211] 'applied index is now lower than readState.Index' (duration: 76.172537ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:26.687816Z","caller":"traceutil/trace.go:171","msg":"trace[1404624898] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"494.51055ms","start":"2026-05-30T01:55:26.193275Z","end":"2026-05-30T01:55:26.687785Z","steps":["trace[1404624898] 'process raft request' (duration: 418.267981ms)","trace[1404624898] 'compare' (duration: 76.028754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:26.687964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:26.19325Z","time spent":"494.626433ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":878,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:26.688127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.316097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4d16ea9c-909c-4333-affe-7ff113a30593\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-30T01:55:26.688188Z","caller":"traceutil/trace.go:171","msg":"trace[1611292270] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4d16ea9c-909c-4333-affe-7ff113a30593; range_end:; response_count:1; response_revision:4761; }","duration":"493.432799ms","start":"2026-05-30T01:55:26.194742Z","end":"2026-05-30T01:55:26.688175Z","steps":["trace[1611292270] 'agreement among raft nodes before linearized reading' (duration: 493.095952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:26.688227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:26.194724Z","time spent":"493.49166ms","remote":"127.0.0.1:51712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1472,"request content":"key:\"/registry/persistentvolumes/pvc-4d16ea9c-909c-4333-affe-7ff113a30593\" "} {"level":"info","ts":"2026-05-30T01:55:26.776919Z","caller":"traceutil/trace.go:171","msg":"trace[798957938] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"581.445322ms","start":"2026-05-30T01:55:26.19544Z","end":"2026-05-30T01:55:26.776886Z","steps":["trace[798957938] 'process raft request' (duration: 581.193367ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:26.776916Z","caller":"traceutil/trace.go:171","msg":"trace[675769680] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"568.561414ms","start":"2026-05-30T01:55:26.20833Z","end":"2026-05-30T01:55:26.776892Z","steps":["trace[675769680] 'process raft request' (duration: 568.456342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:26.777068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:26.195424Z","time spent":"581.570984ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:26.777106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:26.208294Z","time spent":"568.718727ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:26.778125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.537475ms","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-05-30T01:55:26.778179Z","caller":"traceutil/trace.go:171","msg":"trace[1140699828] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4764; }","duration":"112.624057ms","start":"2026-05-30T01:55:26.665538Z","end":"2026-05-30T01:55:26.778162Z","steps":["trace[1140699828] 'agreement among raft nodes before linearized reading' (duration: 112.444133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:26.778188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.583959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:26.778325Z","caller":"traceutil/trace.go:171","msg":"trace[107420558] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"285.219636ms","start":"2026-05-30T01:55:26.493095Z","end":"2026-05-30T01:55:26.778315Z","steps":["trace[107420558] 'process raft request' (duration: 283.746006ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:26.778233Z","caller":"traceutil/trace.go:171","msg":"trace[1628935533] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4764; }","duration":"187.658661ms","start":"2026-05-30T01:55:26.590556Z","end":"2026-05-30T01:55:26.778214Z","steps":["trace[1628935533] 'agreement among raft nodes before linearized reading' (duration: 187.59866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:26.778504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.693299ms","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-05-30T01:55:26.778551Z","caller":"traceutil/trace.go:171","msg":"trace[214559903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4764; }","duration":"112.740249ms","start":"2026-05-30T01:55:26.665791Z","end":"2026-05-30T01:55:26.778531Z","steps":["trace[214559903] 'agreement among raft nodes before linearized reading' (duration: 112.649817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:26.778076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.563627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:26.778666Z","caller":"traceutil/trace.go:171","msg":"trace[308604413] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4764; }","duration":"377.257341ms","start":"2026-05-30T01:55:26.401399Z","end":"2026-05-30T01:55:26.778656Z","steps":["trace[308604413] 'agreement among raft nodes before linearized reading' (duration: 376.530916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:26.778696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:26.40138Z","time spent":"377.305571ms","remote":"127.0.0.1:48338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-05-30T01:55:26.778974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.068134ms","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-05-30T01:55:26.779023Z","caller":"traceutil/trace.go:171","msg":"trace[1410101814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4764; }","duration":"317.144006ms","start":"2026-05-30T01:55:26.46187Z","end":"2026-05-30T01:55:26.779014Z","steps":["trace[1410101814] 'agreement among raft nodes before linearized reading' (duration: 317.046344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:26.77905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:26.461854Z","time spent":"317.189346ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-30T01:55:27.369117Z","caller":"traceutil/trace.go:171","msg":"trace[1131295356] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4947; }","duration":"237.55744ms","start":"2026-05-30T01:55:27.131542Z","end":"2026-05-30T01:55:27.3691Z","steps":["trace[1131295356] 'read index received' (duration: 237.492029ms)","trace[1131295356] 'applied index is now lower than readState.Index' (duration: 64.551µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:27.369466Z","caller":"traceutil/trace.go:171","msg":"trace[1560242173] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"277.386966ms","start":"2026-05-30T01:55:27.09207Z","end":"2026-05-30T01:55:27.369457Z","steps":["trace[1560242173] 'process raft request' (duration: 276.925537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:27.369736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.191242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-30T01:55:27.369794Z","caller":"traceutil/trace.go:171","msg":"trace[1716170253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4773; }","duration":"238.296704ms","start":"2026-05-30T01:55:27.131489Z","end":"2026-05-30T01:55:27.369786Z","steps":["trace[1716170253] 'agreement among raft nodes before linearized reading' (duration: 238.165452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:27.37019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.5467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-30T01:55:27.37022Z","caller":"traceutil/trace.go:171","msg":"trace[1357874687] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4773; }","duration":"131.616081ms","start":"2026-05-30T01:55:27.238596Z","end":"2026-05-30T01:55:27.370212Z","steps":["trace[1357874687] 'agreement among raft nodes before linearized reading' (duration: 131.521229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:27.370284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.440837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-30T01:55:27.37031Z","caller":"traceutil/trace.go:171","msg":"trace[134372649] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4773; }","duration":"169.495118ms","start":"2026-05-30T01:55:27.200807Z","end":"2026-05-30T01:55:27.370302Z","steps":["trace[134372649] 'agreement among raft nodes before linearized reading' (duration: 169.222853ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:27.59573Z","caller":"traceutil/trace.go:171","msg":"trace[209242240] linearizableReadLoop","detail":"{readStateIndex:4950; appliedIndex:4948; }","duration":"206.149421ms","start":"2026-05-30T01:55:27.389558Z","end":"2026-05-30T01:55:27.595708Z","steps":["trace[209242240] 'read index received' (duration: 21.207174ms)","trace[209242240] 'applied index is now lower than readState.Index' (duration: 184.941347ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:27.595761Z","caller":"traceutil/trace.go:171","msg":"trace[416973652] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"220.441568ms","start":"2026-05-30T01:55:27.375284Z","end":"2026-05-30T01:55:27.595726Z","steps":["trace[416973652] 'process raft request' (duration: 173.513019ms)","trace[416973652] 'compare' (duration: 46.065041ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:27.596029Z","caller":"traceutil/trace.go:171","msg":"trace[907610599] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"156.218023ms","start":"2026-05-30T01:55:27.439799Z","end":"2026-05-30T01:55:27.596017Z","steps":["trace[907610599] 'process raft request' (duration: 155.872336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:27.596167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.597261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:27.59622Z","caller":"traceutil/trace.go:171","msg":"trace[1161234562] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4776; }","duration":"206.686922ms","start":"2026-05-30T01:55:27.389521Z","end":"2026-05-30T01:55:27.596208Z","steps":["trace[1161234562] 'agreement among raft nodes before linearized reading' (duration: 206.316555ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:27.596437Z","caller":"traceutil/trace.go:171","msg":"trace[678417291] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"131.324206ms","start":"2026-05-30T01:55:27.465079Z","end":"2026-05-30T01:55:27.596403Z","steps":["trace[678417291] 'process raft request' (duration: 130.801126ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:27.893714Z","caller":"traceutil/trace.go:171","msg":"trace[743912649] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"247.276754ms","start":"2026-05-30T01:55:27.646379Z","end":"2026-05-30T01:55:27.893656Z","steps":["trace[743912649] 'process raft request' (duration: 247.11853ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:28.747931Z","caller":"traceutil/trace.go:171","msg":"trace[454038024] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"121.985374ms","start":"2026-05-30T01:55:28.625901Z","end":"2026-05-30T01:55:28.747887Z","steps":["trace[454038024] 'process raft request' (duration: 121.572246ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:35.761137Z","caller":"traceutil/trace.go:171","msg":"trace[209111051] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"138.427836ms","start":"2026-05-30T01:55:35.62269Z","end":"2026-05-30T01:55:35.761118Z","steps":["trace[209111051] 'process raft request' (duration: 138.028999ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:37.580917Z","caller":"traceutil/trace.go:171","msg":"trace[897976608] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"130.902518ms","start":"2026-05-30T01:55:37.449869Z","end":"2026-05-30T01:55:37.580772Z","steps":["trace[897976608] 'process raft request' (duration: 76.669488ms)","trace[897976608] 'compare' (duration: 53.964184ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:39.360126Z","caller":"traceutil/trace.go:171","msg":"trace[1205352382] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"101.752199ms","start":"2026-05-30T01:55:39.258353Z","end":"2026-05-30T01:55:39.360106Z","steps":["trace[1205352382] 'process raft request' (duration: 95.245663ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:41.992762Z","caller":"traceutil/trace.go:171","msg":"trace[1139171030] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"187.062683ms","start":"2026-05-30T01:55:41.805675Z","end":"2026-05-30T01:55:41.992737Z","steps":["trace[1139171030] 'process raft request' (duration: 185.600354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:41.992963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.108335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:55:41.993229Z","caller":"traceutil/trace.go:171","msg":"trace[95974154] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5000; }","duration":"169.335948ms","start":"2026-05-30T01:55:41.823757Z","end":"2026-05-30T01:55:41.993093Z","steps":["trace[95974154] 'agreement among raft nodes before linearized reading' (duration: 169.051333ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:41.992768Z","caller":"traceutil/trace.go:171","msg":"trace[970870421] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5179; }","duration":"168.599414ms","start":"2026-05-30T01:55:41.823805Z","end":"2026-05-30T01:55:41.992404Z","steps":["trace[970870421] 'read index received' (duration: 167.466552ms)","trace[970870421] 'applied index is now lower than readState.Index' (duration: 1.131362ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:46.081212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:45.654229Z","time spent":"426.977531ms","remote":"127.0.0.1:50798","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-30T01:55:46.08129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.891956ms","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-05-30T01:55:46.08136Z","caller":"traceutil/trace.go:171","msg":"trace[1313473991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5032; }","duration":"419.004729ms","start":"2026-05-30T01:55:45.662325Z","end":"2026-05-30T01:55:46.08133Z","steps":["trace[1313473991] 'range keys from in-memory index tree' (duration: 418.467698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:46.081398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:45.662301Z","time spent":"419.08883ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-30T01:55:46.110331Z","caller":"traceutil/trace.go:171","msg":"trace[1294719238] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"107.339867ms","start":"2026-05-30T01:55:46.002978Z","end":"2026-05-30T01:55:46.110317Z","steps":["trace[1294719238] 'process raft request' (duration: 107.225315ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:46.569586Z","caller":"traceutil/trace.go:171","msg":"trace[762192849] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"459.256847ms","start":"2026-05-30T01:55:46.110299Z","end":"2026-05-30T01:55:46.569556Z","steps":["trace[762192849] 'read index received' (duration: 378.070672ms)","trace[762192849] 'applied index is now lower than readState.Index' (duration: 81.184365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:46.569762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.147582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:46.569821Z","caller":"traceutil/trace.go:171","msg":"trace[1117546224] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"484.476759ms","start":"2026-05-30T01:55:46.085322Z","end":"2026-05-30T01:55:46.569798Z","steps":["trace[1117546224] 'process raft request' (duration: 402.997689ms)","trace[1117546224] 'compare' (duration: 80.711706ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:46.569888Z","caller":"traceutil/trace.go:171","msg":"trace[1327835995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5034; }","duration":"486.283265ms","start":"2026-05-30T01:55:46.083587Z","end":"2026-05-30T01:55:46.56987Z","steps":["trace[1327835995] 'agreement among raft nodes before linearized reading' (duration: 486.074191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:46.570043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.083536Z","time spent":"486.450878ms","remote":"127.0.0.1:51596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-30T01:55:46.569938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.085308Z","time spent":"484.565002ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:46.570033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.648436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-05-30T01:55:46.570119Z","caller":"traceutil/trace.go:171","msg":"trace[1052069778] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5034; }","duration":"468.744838ms","start":"2026-05-30T01:55:46.101356Z","end":"2026-05-30T01:55:46.570101Z","steps":["trace[1052069778] 'agreement among raft nodes before linearized reading' (duration: 468.536824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:46.570172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.101314Z","time spent":"468.84576ms","remote":"127.0.0.1:56358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3265,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-30T01:55:46.906933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.821416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:55:46.907103Z","caller":"traceutil/trace.go:171","msg":"trace[1314468214] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"301.634297ms","start":"2026-05-30T01:55:46.605453Z","end":"2026-05-30T01:55:46.907087Z","steps":["trace[1314468214] 'process raft request' (duration: 301.578946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:46.907162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.605391Z","time spent":"301.74453ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":794,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-30T01:55:46.907207Z","caller":"traceutil/trace.go:171","msg":"trace[645370421] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"320.317835ms","start":"2026-05-30T01:55:46.586857Z","end":"2026-05-30T01:55:46.907175Z","steps":["trace[645370421] 'process raft request' (duration: 130.169493ms)","trace[645370421] 'compare' (duration: 189.675423ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:46.907439Z","caller":"traceutil/trace.go:171","msg":"trace[294988221] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5215; }","duration":"317.840128ms","start":"2026-05-30T01:55:46.589582Z","end":"2026-05-30T01:55:46.907422Z","steps":["trace[294988221] 'read index received' (duration: 127.458441ms)","trace[294988221] 'applied index is now lower than readState.Index' (duration: 190.379536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:46.907459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.58678Z","time spent":"320.490099ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:46.907716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.125703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-05-30T01:55:46.907771Z","caller":"traceutil/trace.go:171","msg":"trace[302808939] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5036; }","duration":"318.199904ms","start":"2026-05-30T01:55:46.589558Z","end":"2026-05-30T01:55:46.907758Z","steps":["trace[302808939] 'agreement among raft nodes before linearized reading' (duration: 318.00531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:46.907806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.589547Z","time spent":"318.250256ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9659,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-05-30T01:55:46.908069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.646162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-05-30T01:55:46.90811Z","caller":"traceutil/trace.go:171","msg":"trace[1676920796] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5036; }","duration":"296.715583ms","start":"2026-05-30T01:55:46.611384Z","end":"2026-05-30T01:55:46.908099Z","steps":["trace[1676920796] 'agreement among raft nodes before linearized reading' (duration: 296.592711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:46.908303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.125456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-30T01:55:46.908386Z","caller":"traceutil/trace.go:171","msg":"trace[1869894991] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5036; }","duration":"292.240408ms","start":"2026-05-30T01:55:46.616123Z","end":"2026-05-30T01:55:46.908364Z","steps":["trace[1869894991] 'agreement among raft nodes before linearized reading' (duration: 291.870231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.339666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.051179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:55:47.339874Z","caller":"traceutil/trace.go:171","msg":"trace[1145152387] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"426.346533ms","start":"2026-05-30T01:55:46.913507Z","end":"2026-05-30T01:55:47.339854Z","steps":["trace[1145152387] 'process raft request' (duration: 153.031359ms)","trace[1145152387] 'compare' (duration: 272.936277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:47.340059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.913496Z","time spent":"426.525067ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:47.631219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.63194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:55:47.63139Z","caller":"traceutil/trace.go:171","msg":"trace[834429450] linearizableReadLoop","detail":"{readStateIndex:5219; appliedIndex:5217; }","duration":"706.333343ms","start":"2026-05-30T01:55:46.925045Z","end":"2026-05-30T01:55:47.631379Z","steps":["trace[834429450] 'read index received' (duration: 141.506977ms)","trace[834429450] 'applied index is now lower than readState.Index' (duration: 564.825775ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:55:47.631455Z","caller":"traceutil/trace.go:171","msg":"trace[22935112] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"713.771126ms","start":"2026-05-30T01:55:46.917676Z","end":"2026-05-30T01:55:47.631447Z","steps":["trace[22935112] 'process raft request' (duration: 586.35011ms)","trace[22935112] 'compare' (duration: 126.455477ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:55:47.631498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.91766Z","time spent":"713.816666ms","remote":"127.0.0.1:51746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-30T01:55:47.631531Z","caller":"traceutil/trace.go:171","msg":"trace[3384375] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"630.317207ms","start":"2026-05-30T01:55:47.001178Z","end":"2026-05-30T01:55:47.631495Z","steps":["trace[3384375] 'process raft request' (duration: 630.159904ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:55:47.631751Z","caller":"traceutil/trace.go:171","msg":"trace[267153667] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"688.891899ms","start":"2026-05-30T01:55:46.942852Z","end":"2026-05-30T01:55:47.631744Z","steps":["trace[267153667] 'process raft request' (duration: 688.436081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.631767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:47.001152Z","time spent":"630.536421ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:47.631829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.94283Z","time spent":"688.94351ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:55:47.631926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.972923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:47.631949Z","caller":"traceutil/trace.go:171","msg":"trace[2071599360] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:5040; }","duration":"177.030723ms","start":"2026-05-30T01:55:47.454912Z","end":"2026-05-30T01:55:47.631943Z","steps":["trace[2071599360] 'agreement among raft nodes before linearized reading' (duration: 176.990282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.632132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.589836ms","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-05-30T01:55:47.632158Z","caller":"traceutil/trace.go:171","msg":"trace[1606988054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5040; }","duration":"142.644446ms","start":"2026-05-30T01:55:47.489506Z","end":"2026-05-30T01:55:47.63215Z","steps":["trace[1606988054] 'agreement among raft nodes before linearized reading' (duration: 142.540404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.632243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.310995ms","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-05-30T01:55:47.632264Z","caller":"traceutil/trace.go:171","msg":"trace[1352672087] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5040; }","duration":"610.365745ms","start":"2026-05-30T01:55:47.021893Z","end":"2026-05-30T01:55:47.632259Z","steps":["trace[1352672087] 'agreement among raft nodes before linearized reading' (duration: 610.313524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.632268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.21501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"warn","ts":"2026-05-30T01:55:47.632282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:47.021878Z","time spent":"610.397426ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-30T01:55:47.632307Z","caller":"traceutil/trace.go:171","msg":"trace[481010243] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5040; }","duration":"707.268361ms","start":"2026-05-30T01:55:46.925028Z","end":"2026-05-30T01:55:47.632296Z","steps":["trace[481010243] 'agreement among raft nodes before linearized reading' (duration: 707.146458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.63236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:46.92502Z","time spent":"707.333302ms","remote":"127.0.0.1:55506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9659,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-05-30T01:55:47.632444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.503211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-30T01:55:47.632516Z","caller":"traceutil/trace.go:171","msg":"trace[1714577862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5040; }","duration":"606.608903ms","start":"2026-05-30T01:55:47.02589Z","end":"2026-05-30T01:55:47.632499Z","steps":["trace[1714577862] 'agreement among raft nodes before linearized reading' (duration: 606.389769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.632564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:47.02588Z","time spent":"606.675735ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-30T01:55:47.632641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.502235ms","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-05-30T01:55:47.632702Z","caller":"traceutil/trace.go:171","msg":"trace[666809042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5040; }","duration":"625.584546ms","start":"2026-05-30T01:55:47.007102Z","end":"2026-05-30T01:55:47.632686Z","steps":["trace[666809042] 'agreement among raft nodes before linearized reading' (duration: 625.160668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.632755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:47.007091Z","time spent":"625.653917ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T01:55:47.633278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.205546ms","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-05-30T01:55:47.633407Z","caller":"traceutil/trace.go:171","msg":"trace[1235053840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5040; }","duration":"628.375059ms","start":"2026-05-30T01:55:47.005012Z","end":"2026-05-30T01:55:47.633387Z","steps":["trace[1235053840] 'agreement among raft nodes before linearized reading' (duration: 626.8325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:55:47.633544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:55:47.00499Z","time spent":"628.536394ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-30T01:55:47.925195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.605379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:55:47.925254Z","caller":"traceutil/trace.go:171","msg":"trace[91275115] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5051; }","duration":"151.67506ms","start":"2026-05-30T01:55:47.773565Z","end":"2026-05-30T01:55:47.92524Z","steps":["trace[91275115] 'count revisions from in-memory index tree' (duration: 151.482225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:15.041634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.919654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:56:15.041737Z","caller":"traceutil/trace.go:171","msg":"trace[1631341675] linearizableReadLoop","detail":"{readStateIndex:5484; appliedIndex:5483; }","duration":"149.314744ms","start":"2026-05-30T01:56:14.892408Z","end":"2026-05-30T01:56:15.041722Z","steps":["trace[1631341675] 'read index received' (duration: 45.715818ms)","trace[1631341675] 'applied index is now lower than readState.Index' (duration: 103.597716ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:15.041809Z","caller":"traceutil/trace.go:171","msg":"trace[899985038] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"149.815853ms","start":"2026-05-30T01:56:14.891945Z","end":"2026-05-30T01:56:15.041761Z","steps":["trace[899985038] 'process raft request' (duration: 46.326069ms)","trace[899985038] 'compare' (duration: 102.747891ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:56:15.041948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.534768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-30T01:56:15.042029Z","caller":"traceutil/trace.go:171","msg":"trace[205348269] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:5297; }","duration":"149.6287ms","start":"2026-05-30T01:56:14.892385Z","end":"2026-05-30T01:56:15.042014Z","steps":["trace[205348269] 'agreement among raft nodes before linearized reading' (duration: 149.437827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:15.042587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.075389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:61995"} {"level":"info","ts":"2026-05-30T01:56:15.042665Z","caller":"traceutil/trace.go:171","msg":"trace[1900122988] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:5297; }","duration":"150.18461ms","start":"2026-05-30T01:56:14.892464Z","end":"2026-05-30T01:56:15.042649Z","steps":["trace[1900122988] 'agreement among raft nodes before linearized reading' (duration: 149.417046ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:19.964623Z","caller":"traceutil/trace.go:171","msg":"trace[1274727129] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"115.526306ms","start":"2026-05-30T01:56:19.84907Z","end":"2026-05-30T01:56:19.964596Z","steps":["trace[1274727129] 'process raft request' (duration: 100.544616ms)","trace[1274727129] 'compare' (duration: 14.867088ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:28.188546Z","caller":"traceutil/trace.go:171","msg":"trace[1130555277] transaction","detail":"{read_only:false; response_revision:5383; number_of_response:1; }","duration":"127.303382ms","start":"2026-05-30T01:56:28.061207Z","end":"2026-05-30T01:56:28.18851Z","steps":["trace[1130555277] 'process raft request' (duration: 85.873009ms)","trace[1130555277] 'compare' (duration: 41.217289ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:28.367107Z","caller":"traceutil/trace.go:171","msg":"trace[560833610] transaction","detail":"{read_only:false; response_revision:5385; number_of_response:1; }","duration":"172.854598ms","start":"2026-05-30T01:56:28.194213Z","end":"2026-05-30T01:56:28.367067Z","steps":["trace[560833610] 'process raft request' (duration: 114.019917ms)","trace[560833610] 'compare' (duration: 58.576947ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:28.36753Z","caller":"traceutil/trace.go:171","msg":"trace[1941200840] transaction","detail":"{read_only:false; response_revision:5386; number_of_response:1; }","duration":"172.601192ms","start":"2026-05-30T01:56:28.194898Z","end":"2026-05-30T01:56:28.367499Z","steps":["trace[1941200840] 'process raft request' (duration: 172.074813ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:28.367513Z","caller":"traceutil/trace.go:171","msg":"trace[1768853594] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"171.892119ms","start":"2026-05-30T01:56:28.195598Z","end":"2026-05-30T01:56:28.367491Z","steps":["trace[1768853594] 'process raft request' (duration: 171.803068ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:29.122564Z","caller":"traceutil/trace.go:171","msg":"trace[676026531] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"250.132771ms","start":"2026-05-30T01:56:28.872407Z","end":"2026-05-30T01:56:29.12254Z","steps":["trace[676026531] 'process raft request' (duration: 249.946208ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:29.153434Z","caller":"traceutil/trace.go:171","msg":"trace[503861340] linearizableReadLoop","detail":"{readStateIndex:5582; appliedIndex:5580; }","duration":"176.577089ms","start":"2026-05-30T01:56:28.976832Z","end":"2026-05-30T01:56:29.153409Z","steps":["trace[503861340] 'read index received' (duration: 145.567201ms)","trace[503861340] 'applied index is now lower than readState.Index' (duration: 31.008988ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:29.154008Z","caller":"traceutil/trace.go:171","msg":"trace[1218213202] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"246.14151ms","start":"2026-05-30T01:56:28.90782Z","end":"2026-05-30T01:56:29.153961Z","steps":["trace[1218213202] 'process raft request' (duration: 245.444387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.155997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.129364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-05-30T01:56:29.156087Z","caller":"traceutil/trace.go:171","msg":"trace[1021164101] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5391; }","duration":"179.271637ms","start":"2026-05-30T01:56:28.976796Z","end":"2026-05-30T01:56:29.156067Z","steps":["trace[1021164101] 'agreement among raft nodes before linearized reading' (duration: 176.733502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.63049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.005207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:29.630709Z","caller":"traceutil/trace.go:171","msg":"trace[1463940993] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5391; }","duration":"407.255782ms","start":"2026-05-30T01:56:29.223416Z","end":"2026-05-30T01:56:29.630672Z","steps":["trace[1463940993] 'range keys from in-memory index tree' (duration: 406.843464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:29.631538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:29.2234Z","time spent":"408.092886ms","remote":"127.0.0.1:51660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" "} {"level":"info","ts":"2026-05-30T01:56:30.092105Z","caller":"traceutil/trace.go:171","msg":"trace[1514683359] linearizableReadLoop","detail":"{readStateIndex:5584; appliedIndex:5583; }","duration":"102.62141ms","start":"2026-05-30T01:56:29.989458Z","end":"2026-05-30T01:56:30.092079Z","steps":["trace[1514683359] 'read index received' (duration: 102.437207ms)","trace[1514683359] 'applied index is now lower than readState.Index' (duration: 183.073µs)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:30.092153Z","caller":"traceutil/trace.go:171","msg":"trace[1174247746] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"159.83932ms","start":"2026-05-30T01:56:29.932286Z","end":"2026-05-30T01:56:30.092126Z","steps":["trace[1174247746] 'process raft request' (duration: 159.603025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:30.092261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.785352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:56:30.092303Z","caller":"traceutil/trace.go:171","msg":"trace[1088654202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5393; }","duration":"102.871534ms","start":"2026-05-30T01:56:29.98942Z","end":"2026-05-30T01:56:30.092292Z","steps":["trace[1088654202] 'agreement among raft nodes before linearized reading' (duration: 102.765593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:30.378116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.620559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-30T01:56:30.378243Z","caller":"traceutil/trace.go:171","msg":"trace[64002994] linearizableReadLoop","detail":"{readStateIndex:5585; appliedIndex:5584; }","duration":"182.188542ms","start":"2026-05-30T01:56:30.196039Z","end":"2026-05-30T01:56:30.378227Z","steps":["trace[64002994] 'read index received' (duration: 62.198656ms)","trace[64002994] 'applied index is now lower than readState.Index' (duration: 119.988076ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:30.378254Z","caller":"traceutil/trace.go:171","msg":"trace[1769808486] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"334.498706ms","start":"2026-05-30T01:56:30.043734Z","end":"2026-05-30T01:56:30.378233Z","steps":["trace[1769808486] 'process raft request' (duration: 214.435569ms)","trace[1769808486] 'compare' (duration: 119.189662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:56:30.378362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:56:30.043708Z","time spent":"334.592378ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:56:30.378444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.406576ms","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-05-30T01:56:30.37848Z","caller":"traceutil/trace.go:171","msg":"trace[2057839256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5394; }","duration":"182.478637ms","start":"2026-05-30T01:56:30.195992Z","end":"2026-05-30T01:56:30.378471Z","steps":["trace[2057839256] 'agreement among raft nodes before linearized reading' (duration: 182.316564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:30.37872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.755604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-30T01:56:30.378769Z","caller":"traceutil/trace.go:171","msg":"trace[1712448690] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:1; response_revision:5394; }","duration":"172.865476ms","start":"2026-05-30T01:56:30.205892Z","end":"2026-05-30T01:56:30.378758Z","steps":["trace[1712448690] 'agreement among raft nodes before linearized reading' (duration: 172.757734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:56:30.379124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.788837ms","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-05-30T01:56:30.379161Z","caller":"traceutil/trace.go:171","msg":"trace[1888665725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5394; }","duration":"146.903339ms","start":"2026-05-30T01:56:30.23225Z","end":"2026-05-30T01:56:30.379153Z","steps":["trace[1888665725] 'agreement among raft nodes before linearized reading' (duration: 146.816868ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:44.766447Z","caller":"traceutil/trace.go:171","msg":"trace[1166396142] transaction","detail":"{read_only:false; response_revision:5496; number_of_response:1; }","duration":"167.300195ms","start":"2026-05-30T01:56:44.599117Z","end":"2026-05-30T01:56:44.766418Z","steps":["trace[1166396142] 'process raft request' (duration: 72.008912ms)","trace[1166396142] 'compare' (duration: 95.066079ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:56:44.766795Z","caller":"traceutil/trace.go:171","msg":"trace[517405610] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"166.126835ms","start":"2026-05-30T01:56:44.600642Z","end":"2026-05-30T01:56:44.766769Z","steps":["trace[517405610] 'process raft request' (duration: 165.725508ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:44.766979Z","caller":"traceutil/trace.go:171","msg":"trace[1357187495] transaction","detail":"{read_only:false; response_revision:5499; number_of_response:1; }","duration":"166.136685ms","start":"2026-05-30T01:56:44.600818Z","end":"2026-05-30T01:56:44.766955Z","steps":["trace[1357187495] 'process raft request' (duration: 166.026083ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:44.766971Z","caller":"traceutil/trace.go:171","msg":"trace[435931744] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"166.299148ms","start":"2026-05-30T01:56:44.600642Z","end":"2026-05-30T01:56:44.766941Z","steps":["trace[435931744] 'process raft request' (duration: 166.109685ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:44.766944Z","caller":"traceutil/trace.go:171","msg":"trace[613901351] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"126.711815ms","start":"2026-05-30T01:56:44.640215Z","end":"2026-05-30T01:56:44.766927Z","steps":["trace[613901351] 'process raft request' (duration: 126.668104ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:56:47.127821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2846} {"level":"info","ts":"2026-05-30T01:56:47.255947Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2846,"took":"126.535221ms","hash":1544617723,"current-db-size-bytes":53313536,"current-db-size":"53 MB","current-db-size-in-use-bytes":28762112,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-30T01:56:47.256014Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1544617723,"revision":2846,"compact-revision":-1} {"level":"info","ts":"2026-05-30T01:57:20.597428Z","caller":"traceutil/trace.go:171","msg":"trace[1959734628] transaction","detail":"{read_only:false; response_revision:5770; number_of_response:1; }","duration":"193.52569ms","start":"2026-05-30T01:57:20.403875Z","end":"2026-05-30T01:57:20.597401Z","steps":["trace[1959734628] 'process raft request' (duration: 134.440489ms)","trace[1959734628] 'compare' (duration: 58.974329ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:57:20.600301Z","caller":"traceutil/trace.go:171","msg":"trace[956917311] linearizableReadLoop","detail":"{readStateIndex:5974; appliedIndex:5972; }","duration":"148.852306ms","start":"2026-05-30T01:57:20.451428Z","end":"2026-05-30T01:57:20.60028Z","steps":["trace[956917311] 'read index received' (duration: 86.861327ms)","trace[956917311] 'applied index is now lower than readState.Index' (duration: 61.989799ms)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:57:20.600551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.09932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-domain-manage\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:57:20.600615Z","caller":"traceutil/trace.go:171","msg":"trace[2073689542] range","detail":"{range_begin:/registry/jobs/openstack/keystone-domain-manage; range_end:; response_count:0; response_revision:5771; }","duration":"149.179101ms","start":"2026-05-30T01:57:20.45142Z","end":"2026-05-30T01:57:20.600599Z","steps":["trace[2073689542] 'agreement among raft nodes before linearized reading' (duration: 149.034009ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:57:20.600635Z","caller":"traceutil/trace.go:171","msg":"trace[129326833] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"164.749417ms","start":"2026-05-30T01:57:20.435865Z","end":"2026-05-30T01:57:20.600614Z","steps":["trace[129326833] 'process raft request' (duration: 164.29469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:57:20.600773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.344598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:57:20.601159Z","caller":"traceutil/trace.go:171","msg":"trace[413419481] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5771; }","duration":"103.776965ms","start":"2026-05-30T01:57:20.497357Z","end":"2026-05-30T01:57:20.601134Z","steps":["trace[413419481] 'agreement among raft nodes before linearized reading' (duration: 103.169966ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:58:07.027912Z","caller":"traceutil/trace.go:171","msg":"trace[1084703047] transaction","detail":"{read_only:false; response_revision:6018; number_of_response:1; }","duration":"145.49137ms","start":"2026-05-30T01:58:06.882384Z","end":"2026-05-30T01:58:07.027876Z","steps":["trace[1084703047] 'process raft request' (duration: 145.217736ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:58:21.244051Z","caller":"traceutil/trace.go:171","msg":"trace[750042628] linearizableReadLoop","detail":"{readStateIndex:6311; appliedIndex:6310; }","duration":"110.704704ms","start":"2026-05-30T01:58:21.133329Z","end":"2026-05-30T01:58:21.244034Z","steps":["trace[750042628] 'read index received' (duration: 110.624743ms)","trace[750042628] 'applied index is now lower than readState.Index' (duration: 79.451µs)"],"step_count":2} {"level":"warn","ts":"2026-05-30T01:58:21.244358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.016599ms","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-05-30T01:58:21.244389Z","caller":"traceutil/trace.go:171","msg":"trace[1661634180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6096; }","duration":"111.095101ms","start":"2026-05-30T01:58:21.133286Z","end":"2026-05-30T01:58:21.244381Z","steps":["trace[1661634180] 'agreement among raft nodes before linearized reading' (duration: 110.940178ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:58:21.244186Z","caller":"traceutil/trace.go:171","msg":"trace[972478757] transaction","detail":"{read_only:false; response_revision:6096; number_of_response:1; }","duration":"171.081098ms","start":"2026-05-30T01:58:21.073079Z","end":"2026-05-30T01:58:21.24416Z","steps":["trace[972478757] 'process raft request' (duration: 170.854955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:58:21.269922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.101916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-30T01:58:21.270111Z","caller":"traceutil/trace.go:171","msg":"trace[1135601534] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:6097; }","duration":"103.32747ms","start":"2026-05-30T01:58:21.166767Z","end":"2026-05-30T01:58:21.270094Z","steps":["trace[1135601534] 'agreement among raft nodes before linearized reading' (duration: 103.075167ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:58:21.584044Z","caller":"traceutil/trace.go:171","msg":"trace[1265399074] transaction","detail":"{read_only:false; response_revision:6100; number_of_response:1; }","duration":"128.813424ms","start":"2026-05-30T01:58:21.455196Z","end":"2026-05-30T01:58:21.584009Z","steps":["trace[1265399074] 'process raft request' (duration: 29.956944ms)","trace[1265399074] 'compare' (duration: 98.633866ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:59:21.835289Z","caller":"traceutil/trace.go:171","msg":"trace[292147334] linearizableReadLoop","detail":"{readStateIndex:6828; appliedIndex:6826; }","duration":"306.374135ms","start":"2026-05-30T01:59:21.528883Z","end":"2026-05-30T01:59:21.835257Z","steps":["trace[292147334] 'read index received' (duration: 45.076432ms)","trace[292147334] 'applied index is now lower than readState.Index' (duration: 261.296863ms)"],"step_count":2} {"level":"info","ts":"2026-05-30T01:59:21.835349Z","caller":"traceutil/trace.go:171","msg":"trace[1907446173] transaction","detail":"{read_only:false; response_revision:6596; number_of_response:1; }","duration":"331.401798ms","start":"2026-05-30T01:59:21.503916Z","end":"2026-05-30T01:59:21.835318Z","steps":["trace[1907446173] 'process raft request' (duration: 324.520325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:59:21.835552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.229495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-30T01:59:21.835596Z","caller":"traceutil/trace.go:171","msg":"trace[359951221] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:6596; }","duration":"137.353456ms","start":"2026-05-30T01:59:21.698233Z","end":"2026-05-30T01:59:21.835586Z","steps":["trace[359951221] 'agreement among raft nodes before linearized reading' (duration: 137.259875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:59:21.835577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:59:21.503902Z","time spent":"331.57912ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-30T01:59:21.83575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.862862ms","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-05-30T01:59:21.8358Z","caller":"traceutil/trace.go:171","msg":"trace[1945857252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6596; }","duration":"306.941172ms","start":"2026-05-30T01:59:21.528846Z","end":"2026-05-30T01:59:21.835787Z","steps":["trace[1945857252] 'agreement among raft nodes before linearized reading' (duration: 306.495516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-30T01:59:21.835958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-30T01:59:21.528833Z","time spent":"307.112965ms","remote":"127.0.0.1:55296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-30T01:59:21.835919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.161803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"warn","ts":"2026-05-30T01:59:21.836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.55955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"info","ts":"2026-05-30T01:59:21.836023Z","caller":"traceutil/trace.go:171","msg":"trace[1759209134] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6596; }","duration":"284.285365ms","start":"2026-05-30T01:59:21.551729Z","end":"2026-05-30T01:59:21.836014Z","steps":["trace[1759209134] 'agreement among raft nodes before linearized reading' (duration: 283.674806ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T01:59:21.836057Z","caller":"traceutil/trace.go:171","msg":"trace[83667887] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6596; }","duration":"223.649471ms","start":"2026-05-30T01:59:21.612392Z","end":"2026-05-30T01:59:21.836041Z","steps":["trace[83667887] 'agreement among raft nodes before linearized reading' (duration: 223.452438ms)"],"step_count":1} {"level":"info","ts":"2026-05-30T02:01:47.134057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5515} {"level":"info","ts":"2026-05-30T02:01:47.220118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5515,"took":"83.62422ms","hash":2334681932,"current-db-size-bytes":53313536,"current-db-size":"53 MB","current-db-size-in-use-bytes":18472960,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-30T02:01:47.220177Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2334681932,"revision":5515,"compact-revision":2846}