{"level":"warn","ts":"2026-08-13T00:54:44.697552Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T00:54:44.697648Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.134: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.19.213.134:2380","--initial-cluster=instance=https://199.19.213.134:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.134:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.134: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-08-13T00:54:44.69772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T00:54:44.697727Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.134:2380"]} {"level":"info","ts":"2026-08-13T00:54:44.697748Z","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-08-13T00:54:44.698465Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"]} {"level":"info","ts":"2026-08-13T00:54:44.698544Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.134:2380"],"listen-peer-urls":["https://199.19.213.134:2380"],"advertise-client-urls":["https://199.19.213.134:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.134: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-08-13T00:54:44.701313Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.519442ms"} {"level":"info","ts":"2026-08-13T00:54:44.711562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"92da22d3868c86fb","cluster-id":"84450b28e935eb90"} {"level":"info","ts":"2026-08-13T00:54:44.711675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T00:54:44.711704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became follower at term 0"} {"level":"info","ts":"2026-08-13T00:54:44.711731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 92da22d3868c86fb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T00:54:44.711745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became follower at term 1"} {"level":"info","ts":"2026-08-13T00:54:44.711808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=(10581808566351202043)"} {"level":"warn","ts":"2026-08-13T00:54:44.716634Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T00:54:44.718546Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T00:54:44.719867Z","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-08-13T00:54:44.722907Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"92da22d3868c86fb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T00:54:44.723163Z","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-08-13T00:54:44.723337Z","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-08-13T00:54:44.72336Z","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-08-13T00:54:44.723236Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"92da22d3868c86fb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T00:54:44.724027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb switched to configuration voters=(10581808566351202043)"} {"level":"info","ts":"2026-08-13T00:54:44.726419Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"84450b28e935eb90","local-member-id":"92da22d3868c86fb","added-peer-id":"92da22d3868c86fb","added-peer-peer-urls":["https://199.19.213.134:2380"]} {"level":"info","ts":"2026-08-13T00:54:44.729985Z","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-08-13T00:54:44.730168Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.134:2380"} {"level":"info","ts":"2026-08-13T00:54:44.730249Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.134:2380"} {"level":"info","ts":"2026-08-13T00:54:44.730588Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"92da22d3868c86fb","initial-advertise-peer-urls":["https://199.19.213.134:2380"],"listen-peer-urls":["https://199.19.213.134:2380"],"advertise-client-urls":["https://199.19.213.134:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.134:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T00:54:44.730649Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T00:54:44.813025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T00:54:44.813239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T00:54:44.813323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb received MsgPreVoteResp from 92da22d3868c86fb at term 1"} {"level":"info","ts":"2026-08-13T00:54:44.813373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became candidate at term 2"} {"level":"info","ts":"2026-08-13T00:54:44.813426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb received MsgVoteResp from 92da22d3868c86fb at term 2"} {"level":"info","ts":"2026-08-13T00:54:44.813478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"92da22d3868c86fb became leader at term 2"} {"level":"info","ts":"2026-08-13T00:54:44.813527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 92da22d3868c86fb elected leader 92da22d3868c86fb at term 2"} {"level":"info","ts":"2026-08-13T00:54:44.814682Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:54:44.815591Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:54:44.815592Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"92da22d3868c86fb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.134:2379]}","request-path":"/0/members/92da22d3868c86fb/attributes","cluster-id":"84450b28e935eb90","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T00:54:44.815668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:54:44.81603Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T00:54:44.816091Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T00:54:44.816192Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"84450b28e935eb90","local-member-id":"92da22d3868c86fb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:54:44.816386Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:54:44.816479Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:54:44.819118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.134:2379"} {"level":"info","ts":"2026-08-13T00:54:44.819465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T00:55:30.598256Z","caller":"traceutil/trace.go:171","msg":"trace[232169160] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"145.526009ms","start":"2026-08-13T00:55:30.452707Z","end":"2026-08-13T00:55:30.598233Z","steps":["trace[232169160] 'process raft request' (duration: 144.593986ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:30.775543Z","caller":"traceutil/trace.go:171","msg":"trace[8000896] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"119.406903ms","start":"2026-08-13T00:55:30.656105Z","end":"2026-08-13T00:55:30.775512Z","steps":["trace[8000896] 'process raft request' (duration: 56.399201ms)","trace[8000896] 'compare' (duration: 62.710105ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:55:31.305122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.552539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:55:31.305314Z","caller":"traceutil/trace.go:171","msg":"trace[1719413797] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"433.911098ms","start":"2026-08-13T00:55:30.871355Z","end":"2026-08-13T00:55:31.305267Z","steps":["trace[1719413797] 'process raft request' (duration: 270.669743ms)","trace[1719413797] 'compare' (duration: 162.414925ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:55:31.305343Z","caller":"traceutil/trace.go:171","msg":"trace[560793210] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"276.277442ms","start":"2026-08-13T00:55:31.028961Z","end":"2026-08-13T00:55:31.305238Z","steps":["trace[560793210] 'read index received' (duration: 112.997436ms)","trace[560793210] 'applied index is now lower than readState.Index' (duration: 163.278876ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:55:31.305511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:55:30.871336Z","time spent":"434.068903ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:55:31.3056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.640242ms","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-08-13T00:55:31.305781Z","caller":"traceutil/trace.go:171","msg":"trace[1771242094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"276.876067ms","start":"2026-08-13T00:55:31.028882Z","end":"2026-08-13T00:55:31.305758Z","steps":["trace[1771242094] 'agreement among raft nodes before linearized reading' (duration: 276.480827ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:55:31.32846Z","caller":"traceutil/trace.go:171","msg":"trace[1836518695] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"120.287026ms","start":"2026-08-13T00:55:31.208132Z","end":"2026-08-13T00:55:31.328419Z","steps":["trace[1836518695] 'process raft request' (duration: 119.68559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:55:31.32853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.836626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jtgcj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-13T00:55:31.328609Z","caller":"traceutil/trace.go:171","msg":"trace[617025057] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jtgcj; range_end:; response_count:1; response_revision:599; }","duration":"278.964109ms","start":"2026-08-13T00:55:31.049624Z","end":"2026-08-13T00:55:31.328588Z","steps":["trace[617025057] 'agreement among raft nodes before linearized reading' (duration: 278.748914ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:57.545054Z","caller":"traceutil/trace.go:171","msg":"trace[548616695] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"266.981397ms","start":"2026-08-13T00:56:57.278048Z","end":"2026-08-13T00:56:57.545029Z","steps":["trace[548616695] 'process raft request' (duration: 266.819803ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:57.554392Z","caller":"traceutil/trace.go:171","msg":"trace[1253167886] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1879; }","duration":"240.821423ms","start":"2026-08-13T00:56:57.313541Z","end":"2026-08-13T00:56:57.554363Z","steps":["trace[1253167886] 'read index received' (duration: 231.398064ms)","trace[1253167886] 'applied index is now lower than readState.Index' (duration: 9.422289ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:57.554487Z","caller":"traceutil/trace.go:171","msg":"trace[1253777708] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"269.754986ms","start":"2026-08-13T00:56:57.284724Z","end":"2026-08-13T00:56:57.554479Z","steps":["trace[1253777708] 'process raft request' (duration: 269.50878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:57.554597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.80933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T00:56:57.554769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.225152ms","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-08-13T00:56:57.554808Z","caller":"traceutil/trace.go:171","msg":"trace[1236782681] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1807; }","duration":"241.296784ms","start":"2026-08-13T00:56:57.313499Z","end":"2026-08-13T00:56:57.554796Z","steps":["trace[1236782681] 'agreement among raft nodes before linearized reading' (duration: 241.13664ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:57.554796Z","caller":"traceutil/trace.go:171","msg":"trace[655536787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1807; }","duration":"149.064998ms","start":"2026-08-13T00:56:57.405712Z","end":"2026-08-13T00:56:57.554777Z","steps":["trace[655536787] 'agreement among raft nodes before linearized reading' (duration: 148.832112ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:58.003863Z","caller":"traceutil/trace.go:171","msg":"trace[1135752401] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1885; }","duration":"150.988964ms","start":"2026-08-13T00:56:57.852852Z","end":"2026-08-13T00:56:58.003841Z","steps":["trace[1135752401] 'read index received' (duration: 136.788929ms)","trace[1135752401] 'applied index is now lower than readState.Index' (duration: 14.199175ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:58.004081Z","caller":"traceutil/trace.go:171","msg":"trace[543112487] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"152.384727ms","start":"2026-08-13T00:56:57.851683Z","end":"2026-08-13T00:56:58.004068Z","steps":["trace[543112487] 'process raft request' (duration: 138.07608ms)","trace[543112487] 'compare' (duration: 13.693682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:58.004303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.446414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f34f3a52-e5a2-4e28-97f8-d7031bcd1888\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-08-13T00:56:58.004335Z","caller":"traceutil/trace.go:171","msg":"trace[1130831373] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f34f3a52-e5a2-4e28-97f8-d7031bcd1888; range_end:; response_count:1; response_revision:1812; }","duration":"151.519176ms","start":"2026-08-13T00:56:57.852807Z","end":"2026-08-13T00:56:58.004327Z","steps":["trace[1130831373] 'agreement among raft nodes before linearized reading' (duration: 151.408014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:58.004364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.213294ms","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-08-13T00:56:58.00442Z","caller":"traceutil/trace.go:171","msg":"trace[267663864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1812; }","duration":"124.301595ms","start":"2026-08-13T00:56:57.880102Z","end":"2026-08-13T00:56:58.004403Z","steps":["trace[267663864] 'agreement among raft nodes before linearized reading' (duration: 124.162462ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:40.612513Z","caller":"traceutil/trace.go:171","msg":"trace[2021196242] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"125.449229ms","start":"2026-08-13T00:57:40.487037Z","end":"2026-08-13T00:57:40.612486Z","steps":["trace[2021196242] 'process raft request' (duration: 125.331456ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:46.343689Z","caller":"traceutil/trace.go:171","msg":"trace[1723202471] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"125.273406ms","start":"2026-08-13T00:57:46.218391Z","end":"2026-08-13T00:57:46.343664Z","steps":["trace[1723202471] 'process raft request' (duration: 124.991829ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:46.525893Z","caller":"traceutil/trace.go:171","msg":"trace[703500724] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"179.250933ms","start":"2026-08-13T00:57:46.346615Z","end":"2026-08-13T00:57:46.525866Z","steps":["trace[703500724] 'read index received' (duration: 123.450111ms)","trace[703500724] 'applied index is now lower than readState.Index' (duration: 55.799512ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:46.52598Z","caller":"traceutil/trace.go:171","msg":"trace[1753533464] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"179.705884ms","start":"2026-08-13T00:57:46.346238Z","end":"2026-08-13T00:57:46.525944Z","steps":["trace[1753533464] 'process raft request' (duration: 123.85011ms)","trace[1753533464] 'compare' (duration: 55.626369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:57:46.526144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.396728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-08-13T00:57:46.52616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.168126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:57:46.52621Z","caller":"traceutil/trace.go:171","msg":"trace[397498813] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2131; }","duration":"177.486901ms","start":"2026-08-13T00:57:46.348708Z","end":"2026-08-13T00:57:46.526195Z","steps":["trace[397498813] 'agreement among raft nodes before linearized reading' (duration: 177.347558ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:46.526221Z","caller":"traceutil/trace.go:171","msg":"trace[875659917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2131; }","duration":"121.271748ms","start":"2026-08-13T00:57:46.404935Z","end":"2026-08-13T00:57:46.526206Z","steps":["trace[875659917] 'agreement among raft nodes before linearized reading' (duration: 121.179166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:46.526256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.619283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:15 size:25780"} {"level":"info","ts":"2026-08-13T00:57:46.526301Z","caller":"traceutil/trace.go:171","msg":"trace[1980389058] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:15; response_revision:2131; }","duration":"179.697004ms","start":"2026-08-13T00:57:46.346592Z","end":"2026-08-13T00:57:46.526289Z","steps":["trace[1980389058] 'agreement among raft nodes before linearized reading' (duration: 179.408818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:46.52643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.840203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-08-13T00:57:46.526455Z","caller":"traceutil/trace.go:171","msg":"trace[2008205201] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2131; }","duration":"121.905964ms","start":"2026-08-13T00:57:46.404543Z","end":"2026-08-13T00:57:46.526449Z","steps":["trace[2008205201] 'agreement among raft nodes before linearized reading' (duration: 121.802642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:57:46.813768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.71627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-13T00:57:46.813823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.113659ms","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-08-13T00:57:46.813854Z","caller":"traceutil/trace.go:171","msg":"trace[1133260078] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2131; }","duration":"185.863803ms","start":"2026-08-13T00:57:46.627969Z","end":"2026-08-13T00:57:46.813833Z","steps":["trace[1133260078] 'count revisions from in-memory index tree' (duration: 185.610107ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:57:46.813904Z","caller":"traceutil/trace.go:171","msg":"trace[2013894255] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2131; }","duration":"176.241502ms","start":"2026-08-13T00:57:46.637642Z","end":"2026-08-13T00:57:46.813883Z","steps":["trace[2013894255] 'range keys from in-memory index tree' (duration: 175.929654ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:59:14.146617Z","caller":"traceutil/trace.go:171","msg":"trace[1327909059] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"119.753973ms","start":"2026-08-13T00:59:14.026828Z","end":"2026-08-13T00:59:14.146582Z","steps":["trace[1327909059] 'process raft request' (duration: 119.567848ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:02.531668Z","caller":"traceutil/trace.go:171","msg":"trace[1625525864] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"122.775036ms","start":"2026-08-13T01:03:02.40887Z","end":"2026-08-13T01:03:02.531645Z","steps":["trace[1625525864] 'process raft request' (duration: 122.652973ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.37203Z","caller":"traceutil/trace.go:171","msg":"trace[146053510] linearizableReadLoop","detail":"{readStateIndex:5552; appliedIndex:5551; }","duration":"135.25392ms","start":"2026-08-13T01:03:23.23675Z","end":"2026-08-13T01:03:23.372004Z","steps":["trace[146053510] 'read index received' (duration: 135.050266ms)","trace[146053510] 'applied index is now lower than readState.Index' (duration: 202.554µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:03:23.372064Z","caller":"traceutil/trace.go:171","msg":"trace[392960187] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"153.762983ms","start":"2026-08-13T01:03:23.218267Z","end":"2026-08-13T01:03:23.37203Z","steps":["trace[392960187] 'process raft request' (duration: 153.518497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:03:23.372256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.484256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnnc7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-13T01:03:23.372297Z","caller":"traceutil/trace.go:171","msg":"trace[100853281] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnnc7; range_end:; response_count:1; response_revision:5350; }","duration":"135.572058ms","start":"2026-08-13T01:03:23.236715Z","end":"2026-08-13T01:03:23.372287Z","steps":["trace[100853281] 'agreement among raft nodes before linearized reading' (duration: 135.390764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:03:23.519109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.311654ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-13T01:03:23.519373Z","caller":"traceutil/trace.go:171","msg":"trace[1434294210] linearizableReadLoop","detail":"{readStateIndex:5554; appliedIndex:5552; }","duration":"142.951299ms","start":"2026-08-13T01:03:23.376404Z","end":"2026-08-13T01:03:23.519356Z","steps":["trace[1434294210] 'read index received' (duration: 27.334698ms)","trace[1434294210] 'applied index is now lower than readState.Index' (duration: 115.615631ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:03:23.519586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.178584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80100"} {"level":"info","ts":"2026-08-13T01:03:23.519509Z","caller":"traceutil/trace.go:171","msg":"trace[439092915] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"179.398739ms","start":"2026-08-13T01:03:23.340021Z","end":"2026-08-13T01:03:23.519419Z","steps":["trace[439092915] 'process raft request' (duration: 179.177804ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.51965Z","caller":"traceutil/trace.go:171","msg":"trace[1828184078] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5351; }","duration":"143.260248ms","start":"2026-08-13T01:03:23.376357Z","end":"2026-08-13T01:03:23.519617Z","steps":["trace[1828184078] 'agreement among raft nodes before linearized reading' (duration: 143.076032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:03:23.553595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.51391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b509dfa9-0430-4b42-adc9-4248babefbd0\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-13T01:03:23.553647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.01058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T01:03:23.553692Z","caller":"traceutil/trace.go:171","msg":"trace[590040767] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b509dfa9-0430-4b42-adc9-4248babefbd0; range_end:; response_count:1; response_revision:5352; }","duration":"122.652314ms","start":"2026-08-13T01:03:23.43102Z","end":"2026-08-13T01:03:23.553673Z","steps":["trace[590040767] 'agreement among raft nodes before linearized reading' (duration: 122.398828ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.553762Z","caller":"traceutil/trace.go:171","msg":"trace[1818610120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5352; }","duration":"168.160174ms","start":"2026-08-13T01:03:23.385583Z","end":"2026-08-13T01:03:23.553744Z","steps":["trace[1818610120] 'agreement among raft nodes before linearized reading' (duration: 167.885998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:03:23.553845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.410753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:03:23.553903Z","caller":"traceutil/trace.go:171","msg":"trace[2088481372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5352; }","duration":"148.497245ms","start":"2026-08-13T01:03:23.405392Z","end":"2026-08-13T01:03:23.553889Z","steps":["trace[2088481372] 'agreement among raft nodes before linearized reading' (duration: 148.404583ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.7459Z","caller":"traceutil/trace.go:171","msg":"trace[1469591100] transaction","detail":"{read_only:false; response_revision:5354; number_of_response:1; }","duration":"187.897176ms","start":"2026-08-13T01:03:23.557965Z","end":"2026-08-13T01:03:23.745863Z","steps":["trace[1469591100] 'process raft request' (duration: 162.293251ms)","trace[1469591100] 'compare' (duration: 25.213036ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:03:23.745985Z","caller":"traceutil/trace.go:171","msg":"trace[933842346] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"187.533687ms","start":"2026-08-13T01:03:23.558432Z","end":"2026-08-13T01:03:23.745966Z","steps":["trace[933842346] 'process raft request' (duration: 187.345903ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.746115Z","caller":"traceutil/trace.go:171","msg":"trace[1978683160] transaction","detail":"{read_only:false; response_revision:5356; number_of_response:1; }","duration":"186.113072ms","start":"2026-08-13T01:03:23.559989Z","end":"2026-08-13T01:03:23.746102Z","steps":["trace[1978683160] 'process raft request' (duration: 185.907827ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.911831Z","caller":"traceutil/trace.go:171","msg":"trace[10066474] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"157.181817ms","start":"2026-08-13T01:03:23.754621Z","end":"2026-08-13T01:03:23.911803Z","steps":["trace[10066474] 'process raft request' (duration: 150.568875ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.911861Z","caller":"traceutil/trace.go:171","msg":"trace[891316905] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"155.774453ms","start":"2026-08-13T01:03:23.756059Z","end":"2026-08-13T01:03:23.911833Z","steps":["trace[891316905] 'process raft request' (duration: 155.621738ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:03:23.91303Z","caller":"traceutil/trace.go:171","msg":"trace[362091913] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"155.446835ms","start":"2026-08-13T01:03:23.757556Z","end":"2026-08-13T01:03:23.913003Z","steps":["trace[362091913] 'process raft request' (duration: 154.202904ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:05.457732Z","caller":"traceutil/trace.go:171","msg":"trace[543010110] linearizableReadLoop","detail":"{readStateIndex:6002; appliedIndex:6001; }","duration":"293.558914ms","start":"2026-08-13T01:04:05.164136Z","end":"2026-08-13T01:04:05.457695Z","steps":["trace[543010110] 'read index received' (duration: 293.353579ms)","trace[543010110] 'applied index is now lower than readState.Index' (duration: 203.585µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:05.457829Z","caller":"traceutil/trace.go:171","msg":"trace[222825326] transaction","detail":"{read_only:false; response_revision:5790; number_of_response:1; }","duration":"343.461067ms","start":"2026-08-13T01:04:05.114329Z","end":"2026-08-13T01:04:05.45779Z","steps":["trace[222825326] 'process raft request' (duration: 343.114469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:05.458056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.882552ms","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-08-13T01:04:05.45811Z","caller":"traceutil/trace.go:171","msg":"trace[2108845986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5790; }","duration":"294.006075ms","start":"2026-08-13T01:04:05.164088Z","end":"2026-08-13T01:04:05.458094Z","steps":["trace[2108845986] 'agreement among raft nodes before linearized reading' (duration: 293.77228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:05.45824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:05.114299Z","time spent":"343.605191ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:04:05.48129Z","caller":"traceutil/trace.go:171","msg":"trace[1096674296] transaction","detail":"{read_only:false; response_revision:5791; number_of_response:1; }","duration":"266.814648ms","start":"2026-08-13T01:04:05.214446Z","end":"2026-08-13T01:04:05.48126Z","steps":["trace[1096674296] 'process raft request' (duration: 266.617673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:11.94965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452412862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19665"} {"level":"warn","ts":"2026-08-13T01:04:11.949723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544353675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:11.949748Z","caller":"traceutil/trace.go:171","msg":"trace[799818646] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5823; }","duration":"1.452563355s","start":"2026-08-13T01:04:10.497164Z","end":"2026-08-13T01:04:11.949727Z","steps":["trace[799818646] 'range keys from in-memory index tree' (duration: 1.452205786s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:11.949789Z","caller":"traceutil/trace.go:171","msg":"trace[237654633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5823; }","duration":"1.544464338s","start":"2026-08-13T01:04:10.405307Z","end":"2026-08-13T01:04:11.949771Z","steps":["trace[237654633] 'range keys from in-memory index tree' (duration: 1.544265232s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:11.949794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:10.497131Z","time spent":"1.452653218s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19689,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-13T01:04:11.949819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:10.405285Z","time spent":"1.5445275s","remote":"127.0.0.1:42086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:04:11.949946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022805005s","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-08-13T01:04:11.950015Z","caller":"traceutil/trace.go:171","msg":"trace[141759945] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5823; }","duration":"1.022920258s","start":"2026-08-13T01:04:10.927078Z","end":"2026-08-13T01:04:11.949998Z","steps":["trace[141759945] 'range keys from in-memory index tree' (duration: 1.022541298s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:11.950045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:10.927056Z","time spent":"1.02298223s","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-13T01:04:11.95015Z","caller":"traceutil/trace.go:171","msg":"trace[1055622341] transaction","detail":"{read_only:false; response_revision:5824; number_of_response:1; }","duration":"464.944454ms","start":"2026-08-13T01:04:11.485138Z","end":"2026-08-13T01:04:11.950083Z","steps":["trace[1055622341] 'process raft request' (duration: 444.974484ms)","trace[1055622341] 'compare' (duration: 19.453317ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:04:11.950309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.48511Z","time spent":"465.115748ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:04:12.185307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.268906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:04:12.18561Z","caller":"traceutil/trace.go:171","msg":"trace[457862460] linearizableReadLoop","detail":"{readStateIndex:6038; appliedIndex:6036; }","duration":"632.273614ms","start":"2026-08-13T01:04:11.553311Z","end":"2026-08-13T01:04:12.185585Z","steps":["trace[457862460] 'read index received' (duration: 376.878865ms)","trace[457862460] 'applied index is now lower than readState.Index' (duration: 255.391789ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:12.185624Z","caller":"traceutil/trace.go:171","msg":"trace[942501251] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"670.129383ms","start":"2026-08-13T01:04:11.515429Z","end":"2026-08-13T01:04:12.185559Z","steps":["trace[942501251] 'process raft request' (duration: 536.525358ms)","trace[942501251] 'compare' (duration: 133.115542ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:12.185721Z","caller":"traceutil/trace.go:171","msg":"trace[1687735689] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"493.136945ms","start":"2026-08-13T01:04:11.692566Z","end":"2026-08-13T01:04:12.185703Z","steps":["trace[1687735689] 'process raft request' (duration: 492.860428ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:12.185752Z","caller":"traceutil/trace.go:171","msg":"trace[1830977097] transaction","detail":"{read_only:false; response_revision:5827; number_of_response:1; }","duration":"231.927604ms","start":"2026-08-13T01:04:11.953795Z","end":"2026-08-13T01:04:12.185722Z","steps":["trace[1830977097] 'process raft request' (duration: 231.740669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.185819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.692554Z","time spent":"493.214077ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:04:12.185836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.515405Z","time spent":"670.316267ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:04:12.186096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.769327ms","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-08-13T01:04:12.186184Z","caller":"traceutil/trace.go:171","msg":"trace[1474188093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5827; }","duration":"632.90339ms","start":"2026-08-13T01:04:11.55326Z","end":"2026-08-13T01:04:12.186163Z","steps":["trace[1474188093] 'agreement among raft nodes before linearized reading' (duration: 632.595243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.186231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.553244Z","time spent":"632.977692ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T01:04:12.198608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.552115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:04:12.198639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.590707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:12.198671Z","caller":"traceutil/trace.go:171","msg":"trace[1430388753] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5828; }","duration":"644.644267ms","start":"2026-08-13T01:04:11.554011Z","end":"2026-08-13T01:04:12.198655Z","steps":["trace[1430388753] 'agreement among raft nodes before linearized reading' (duration: 644.503793ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:12.198697Z","caller":"traceutil/trace.go:171","msg":"trace[147602277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5828; }","duration":"247.673809ms","start":"2026-08-13T01:04:11.951007Z","end":"2026-08-13T01:04:12.19868Z","steps":["trace[147602277] 'agreement among raft nodes before linearized reading' (duration: 247.576807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.198741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.554Z","time spent":"644.712239ms","remote":"127.0.0.1:55472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-13T01:04:12.198764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.953408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-13T01:04:12.198786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.958797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:12.198816Z","caller":"traceutil/trace.go:171","msg":"trace[1271867729] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5828; }","duration":"235.038119ms","start":"2026-08-13T01:04:11.963764Z","end":"2026-08-13T01:04:12.198802Z","steps":["trace[1271867729] 'agreement among raft nodes before linearized reading' (duration: 234.900056ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:12.198852Z","caller":"traceutil/trace.go:171","msg":"trace[697918875] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5828; }","duration":"510.072709ms","start":"2026-08-13T01:04:11.688762Z","end":"2026-08-13T01:04:12.198834Z","steps":["trace[697918875] 'agreement among raft nodes before linearized reading' (duration: 509.937466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.198914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.68874Z","time spent":"510.161602ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-08-13T01:04:12.198978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.256417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T01:04:12.199048Z","caller":"traceutil/trace.go:171","msg":"trace[1462138446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5828; }","duration":"322.3619ms","start":"2026-08-13T01:04:11.876668Z","end":"2026-08-13T01:04:12.199029Z","steps":["trace[1462138446] 'agreement among raft nodes before linearized reading' (duration: 322.149365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.199102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.876646Z","time spent":"322.443401ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T01:04:12.199123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.296836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19665"} {"level":"warn","ts":"2026-08-13T01:04:12.199123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.730706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-08-13T01:04:12.199155Z","caller":"traceutil/trace.go:171","msg":"trace[1610555129] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5828; }","duration":"244.364328ms","start":"2026-08-13T01:04:11.954781Z","end":"2026-08-13T01:04:12.199146Z","steps":["trace[1610555129] 'agreement among raft nodes before linearized reading' (duration: 244.257375ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:12.199164Z","caller":"traceutil/trace.go:171","msg":"trace[837491895] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5828; }","duration":"510.772838ms","start":"2026-08-13T01:04:11.688379Z","end":"2026-08-13T01:04:12.199152Z","steps":["trace[837491895] 'agreement among raft nodes before linearized reading' (duration: 510.658944ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.199186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.688355Z","time spent":"510.825939ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6183,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-08-13T01:04:12.199468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.317307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnnc7\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-08-13T01:04:12.199608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.827333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:12.199662Z","caller":"traceutil/trace.go:171","msg":"trace[1171949119] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5828; }","duration":"513.918105ms","start":"2026-08-13T01:04:11.685734Z","end":"2026-08-13T01:04:12.199652Z","steps":["trace[1171949119] 'agreement among raft nodes before linearized reading' (duration: 513.839833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.199682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.685712Z","time spent":"513.965776ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"info","ts":"2026-08-13T01:04:12.199608Z","caller":"traceutil/trace.go:171","msg":"trace[963887659] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnnc7; range_end:; response_count:1; response_revision:5828; }","duration":"448.49894ms","start":"2026-08-13T01:04:11.751086Z","end":"2026-08-13T01:04:12.199585Z","steps":["trace[963887659] 'agreement among raft nodes before linearized reading' (duration: 448.040458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.199812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:11.751068Z","time spent":"448.723826ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-vnnc7\" "} {"level":"info","ts":"2026-08-13T01:04:12.403182Z","caller":"traceutil/trace.go:171","msg":"trace[880766681] linearizableReadLoop","detail":"{readStateIndex:6044; appliedIndex:6042; }","duration":"188.043928ms","start":"2026-08-13T01:04:12.215114Z","end":"2026-08-13T01:04:12.403158Z","steps":["trace[880766681] 'read index received' (duration: 153.121613ms)","trace[880766681] 'applied index is now lower than readState.Index' (duration: 34.921185ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:12.403226Z","caller":"traceutil/trace.go:171","msg":"trace[1347741697] transaction","detail":"{read_only:false; response_revision:5830; number_of_response:1; }","duration":"198.620057ms","start":"2026-08-13T01:04:12.204567Z","end":"2026-08-13T01:04:12.403187Z","steps":["trace[1347741697] 'process raft request' (duration: 163.822195ms)","trace[1347741697] 'compare' (duration: 34.573026ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:04:12.403452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.551747ms","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-08-13T01:04:12.403557Z","caller":"traceutil/trace.go:171","msg":"trace[143636953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5831; }","duration":"192.692671ms","start":"2026-08-13T01:04:12.21084Z","end":"2026-08-13T01:04:12.403532Z","steps":["trace[143636953] 'agreement among raft nodes before linearized reading' (duration: 192.390834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.403349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.572717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:04:12.403632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.346206ms","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-08-13T01:04:12.40364Z","caller":"traceutil/trace.go:171","msg":"trace[611194901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5831; }","duration":"196.895714ms","start":"2026-08-13T01:04:12.206725Z","end":"2026-08-13T01:04:12.40362Z","steps":["trace[611194901] 'agreement among raft nodes before linearized reading' (duration: 196.567606ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:12.403634Z","caller":"traceutil/trace.go:171","msg":"trace[314708643] transaction","detail":"{read_only:false; response_revision:5831; number_of_response:1; }","duration":"198.997856ms","start":"2026-08-13T01:04:12.20458Z","end":"2026-08-13T01:04:12.403578Z","steps":["trace[314708643] 'process raft request' (duration: 198.515045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.403644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.160731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:11 size:101742"} {"level":"info","ts":"2026-08-13T01:04:12.403701Z","caller":"traceutil/trace.go:171","msg":"trace[915871210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5831; }","duration":"186.4596ms","start":"2026-08-13T01:04:12.217225Z","end":"2026-08-13T01:04:12.403685Z","steps":["trace[915871210] 'agreement among raft nodes before linearized reading' (duration: 186.272194ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:12.403732Z","caller":"traceutil/trace.go:171","msg":"trace[1614659813] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:11; response_revision:5831; }","duration":"199.252624ms","start":"2026-08-13T01:04:12.204469Z","end":"2026-08-13T01:04:12.403722Z","steps":["trace[1614659813] 'agreement among raft nodes before linearized reading' (duration: 198.909705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:12.403744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.392813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19665"} {"level":"info","ts":"2026-08-13T01:04:12.403885Z","caller":"traceutil/trace.go:171","msg":"trace[488525400] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5831; }","duration":"187.543256ms","start":"2026-08-13T01:04:12.216295Z","end":"2026-08-13T01:04:12.403839Z","steps":["trace[488525400] 'agreement among raft nodes before linearized reading' (duration: 187.185316ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:13.075622Z","caller":"traceutil/trace.go:171","msg":"trace[364662222] transaction","detail":"{read_only:false; response_revision:5836; number_of_response:1; }","duration":"111.920393ms","start":"2026-08-13T01:04:12.963671Z","end":"2026-08-13T01:04:13.075591Z","steps":["trace[364662222] 'process raft request' (duration: 111.708198ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:13.50596Z","caller":"traceutil/trace.go:171","msg":"trace[373770592] linearizableReadLoop","detail":"{readStateIndex:6051; appliedIndex:6050; }","duration":"100.713638ms","start":"2026-08-13T01:04:13.404988Z","end":"2026-08-13T01:04:13.505701Z","steps":["trace[373770592] 'read index received' (duration: 53.283786ms)","trace[373770592] 'applied index is now lower than readState.Index' (duration: 47.428392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:04:13.506039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.023596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:13.506202Z","caller":"traceutil/trace.go:171","msg":"trace[955421073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5836; }","duration":"101.150119ms","start":"2026-08-13T01:04:13.404947Z","end":"2026-08-13T01:04:13.506097Z","steps":["trace[955421073] 'agreement among raft nodes before linearized reading' (duration: 100.991115ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:14.198556Z","caller":"traceutil/trace.go:171","msg":"trace[1290861261] transaction","detail":"{read_only:false; response_revision:5838; number_of_response:1; }","duration":"112.533267ms","start":"2026-08-13T01:04:14.085997Z","end":"2026-08-13T01:04:14.19853Z","steps":["trace[1290861261] 'process raft request' (duration: 112.22731ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:14.29619Z","caller":"traceutil/trace.go:171","msg":"trace[1800624050] transaction","detail":"{read_only:false; response_revision:5839; number_of_response:1; }","duration":"103.829674ms","start":"2026-08-13T01:04:14.192333Z","end":"2026-08-13T01:04:14.296163Z","steps":["trace[1800624050] 'process raft request' (duration: 92.437545ms)","trace[1800624050] 'compare' (duration: 11.194704ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:14.296312Z","caller":"traceutil/trace.go:171","msg":"trace[1968839189] transaction","detail":"{read_only:false; response_revision:5840; number_of_response:1; }","duration":"103.938837ms","start":"2026-08-13T01:04:14.192344Z","end":"2026-08-13T01:04:14.296283Z","steps":["trace[1968839189] 'process raft request' (duration: 103.772813ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:14.307645Z","caller":"traceutil/trace.go:171","msg":"trace[1469299497] linearizableReadLoop","detail":"{readStateIndex:6056; appliedIndex:6053; }","duration":"103.454526ms","start":"2026-08-13T01:04:14.204166Z","end":"2026-08-13T01:04:14.307621Z","steps":["trace[1469299497] 'read index received' (duration: 80.753919ms)","trace[1469299497] 'applied index is now lower than readState.Index' (duration: 22.699227ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:14.307685Z","caller":"traceutil/trace.go:171","msg":"trace[1991242531] transaction","detail":"{read_only:false; response_revision:5841; number_of_response:1; }","duration":"105.209688ms","start":"2026-08-13T01:04:14.202452Z","end":"2026-08-13T01:04:14.307662Z","steps":["trace[1991242531] 'process raft request' (duration: 105.041454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:14.307861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.683171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"warn","ts":"2026-08-13T01:04:14.307865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.42237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:14.307902Z","caller":"traceutil/trace.go:171","msg":"trace[37568343] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5841; }","duration":"103.774523ms","start":"2026-08-13T01:04:14.204117Z","end":"2026-08-13T01:04:14.307892Z","steps":["trace[37568343] 'agreement among raft nodes before linearized reading' (duration: 103.62494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:14.307915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.255705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:04:14.307987Z","caller":"traceutil/trace.go:171","msg":"trace[1990904556] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5841; }","duration":"102.347008ms","start":"2026-08-13T01:04:14.205624Z","end":"2026-08-13T01:04:14.307971Z","steps":["trace[1990904556] 'agreement among raft nodes before linearized reading' (duration: 102.237485ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:14.307928Z","caller":"traceutil/trace.go:171","msg":"trace[949829747] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5841; }","duration":"102.532802ms","start":"2026-08-13T01:04:14.20538Z","end":"2026-08-13T01:04:14.307913Z","steps":["trace[949829747] 'agreement among raft nodes before linearized reading' (duration: 102.417899ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:45.005582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2967} {"level":"info","ts":"2026-08-13T01:04:45.078542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2967,"took":"71.39054ms","hash":1902633276,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":34091008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-13T01:04:45.07861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1902633276,"revision":2967,"compact-revision":-1} {"level":"warn","ts":"2026-08-13T01:04:46.890954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543710376107640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:04:47.392085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9726543710376107640,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:04:47.413736Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.335771055s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T01:04:47.414205Z","caller":"traceutil/trace.go:171","msg":"trace[1312299285] transaction","detail":"{read_only:false; response_revision:6099; number_of_response:1; }","duration":"1.336312957s","start":"2026-08-13T01:04:46.077863Z","end":"2026-08-13T01:04:47.414176Z","steps":["trace[1312299285] 'process raft request' (duration: 1.336145873s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.414423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.077841Z","time spent":"1.336442341s","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:04:47.448329Z","caller":"traceutil/trace.go:171","msg":"trace[1687937731] transaction","detail":"{read_only:false; response_revision:6100; number_of_response:1; }","duration":"1.045029759s","start":"2026-08-13T01:04:46.403273Z","end":"2026-08-13T01:04:47.448303Z","steps":["trace[1687937731] 'process raft request' (duration: 1.044888876s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.448466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.403256Z","time spent":"1.045130531s","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:04:47.448489Z","caller":"traceutil/trace.go:171","msg":"trace[1703547440] linearizableReadLoop","detail":"{readStateIndex:6323; appliedIndex:6320; }","duration":"1.057916124s","start":"2026-08-13T01:04:46.390552Z","end":"2026-08-13T01:04:47.448469Z","steps":["trace[1703547440] 'read index received' (duration: 1.02343154s)","trace[1703547440] 'applied index is now lower than readState.Index' (duration: 34.482794ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:04:47.448518Z","caller":"traceutil/trace.go:171","msg":"trace[1207069126] transaction","detail":"{read_only:false; response_revision:6101; number_of_response:1; }","duration":"1.04464081s","start":"2026-08-13T01:04:46.403858Z","end":"2026-08-13T01:04:47.448499Z","steps":["trace[1207069126] 'process raft request' (duration: 1.044413805s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.448683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.403852Z","time spent":"1.044747263s","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:04:47.448914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.882006ms","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-08-13T01:04:47.448979Z","caller":"traceutil/trace.go:171","msg":"trace[612817514] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6101; }","duration":"993.980628ms","start":"2026-08-13T01:04:46.45498Z","end":"2026-08-13T01:04:47.448961Z","steps":["trace[612817514] 'agreement among raft nodes before linearized reading' (duration: 993.751442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.449023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.454963Z","time spent":"994.05168ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-13T01:04:47.449096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.657555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-13T01:04:47.449204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.512721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:04:47.449287Z","caller":"traceutil/trace.go:171","msg":"trace[242256599] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:6101; }","duration":"419.650124ms","start":"2026-08-13T01:04:47.029597Z","end":"2026-08-13T01:04:47.449247Z","steps":["trace[242256599] 'agreement among raft nodes before linearized reading' (duration: 419.523021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.449297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.467404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T01:04:47.449333Z","caller":"traceutil/trace.go:171","msg":"trace[1399403371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6101; }","duration":"702.534106ms","start":"2026-08-13T01:04:46.746789Z","end":"2026-08-13T01:04:47.449324Z","steps":["trace[1399403371] 'agreement among raft nodes before linearized reading' (duration: 702.425533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.449374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.746768Z","time spent":"702.598678ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T01:04:47.449367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:47.029574Z","time spent":"419.752426ms","remote":"127.0.0.1:42326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":31,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-08-13T01:04:47.449208Z","caller":"traceutil/trace.go:171","msg":"trace[1458686409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6101; }","duration":"701.803529ms","start":"2026-08-13T01:04:46.74738Z","end":"2026-08-13T01:04:47.449184Z","steps":["trace[1458686409] 'agreement among raft nodes before linearized reading' (duration: 701.441869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.449565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.747312Z","time spent":"702.235339ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-13T01:04:47.449614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.05556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnnc7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-13T01:04:47.449658Z","caller":"traceutil/trace.go:171","msg":"trace[1116534474] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnnc7; range_end:; response_count:1; response_revision:6101; }","duration":"801.118822ms","start":"2026-08-13T01:04:46.64853Z","end":"2026-08-13T01:04:47.449648Z","steps":["trace[1116534474] 'agreement among raft nodes before linearized reading' (duration: 800.984089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.4495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.571503ms","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-08-13T01:04:47.449732Z","caller":"traceutil/trace.go:171","msg":"trace[1244227460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6101; }","duration":"701.83302ms","start":"2026-08-13T01:04:46.747885Z","end":"2026-08-13T01:04:47.449718Z","steps":["trace[1244227460] 'agreement among raft nodes before linearized reading' (duration: 701.350768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.449686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.64852Z","time spent":"801.160323ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-vnnc7\" "} {"level":"warn","ts":"2026-08-13T01:04:47.449817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.747861Z","time spent":"701.941682ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T01:04:47.449846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.453246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-13T01:04:47.449941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05936142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8317"} {"level":"info","ts":"2026-08-13T01:04:47.450001Z","caller":"traceutil/trace.go:171","msg":"trace[944842774] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:6101; }","duration":"1.059442722s","start":"2026-08-13T01:04:46.390547Z","end":"2026-08-13T01:04:47.44999Z","steps":["trace[944842774] 'agreement among raft nodes before linearized reading' (duration: 1.058026387s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.450039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.390498Z","time spent":"1.059533934s","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":8341,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-08-13T01:04:47.44976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.914283ms","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-08-13T01:04:47.450098Z","caller":"traceutil/trace.go:171","msg":"trace[439746677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6101; }","duration":"809.263231ms","start":"2026-08-13T01:04:46.640814Z","end":"2026-08-13T01:04:47.450077Z","steps":["trace[439746677] 'agreement among raft nodes before linearized reading' (duration: 808.838011ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:04:47.449959Z","caller":"traceutil/trace.go:171","msg":"trace[879365522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6101; }","duration":"953.61982ms","start":"2026-08-13T01:04:46.496321Z","end":"2026-08-13T01:04:47.44994Z","steps":["trace[879365522] 'agreement among raft nodes before linearized reading' (duration: 953.313453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:04:47.450142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.640799Z","time spent":"809.331824ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T01:04:47.450159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:04:46.496303Z","time spent":"953.837135ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-13T01:09:31.05959Z","caller":"traceutil/trace.go:171","msg":"trace[1527252967] transaction","detail":"{read_only:false; response_revision:8183; number_of_response:1; }","duration":"101.131583ms","start":"2026-08-13T01:09:30.958435Z","end":"2026-08-13T01:09:31.059566Z","steps":["trace[1527252967] 'process raft request' (duration: 96.648854ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:31.839154Z","caller":"traceutil/trace.go:171","msg":"trace[1142321039] transaction","detail":"{read_only:false; response_revision:8185; number_of_response:1; }","duration":"276.351906ms","start":"2026-08-13T01:09:31.562777Z","end":"2026-08-13T01:09:31.839129Z","steps":["trace[1142321039] 'process raft request' (duration: 276.154461ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:33.071521Z","caller":"traceutil/trace.go:171","msg":"trace[1056949350] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"121.736689ms","start":"2026-08-13T01:09:32.949759Z","end":"2026-08-13T01:09:33.071496Z","steps":["trace[1056949350] 'process raft request' (duration: 121.584515ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:33.078614Z","caller":"traceutil/trace.go:171","msg":"trace[393105534] transaction","detail":"{read_only:false; response_revision:8192; number_of_response:1; }","duration":"128.170008ms","start":"2026-08-13T01:09:32.950426Z","end":"2026-08-13T01:09:33.078596Z","steps":["trace[393105534] 'process raft request' (duration: 128.115237ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:33.078644Z","caller":"traceutil/trace.go:171","msg":"trace[651350379] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"128.65036ms","start":"2026-08-13T01:09:32.949974Z","end":"2026-08-13T01:09:33.078624Z","steps":["trace[651350379] 'process raft request' (duration: 128.482555ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:09:45.014582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6096} {"level":"info","ts":"2026-08-13T01:09:45.113453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6096,"took":"96.814058ms","hash":4006782052,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":22982656,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-13T01:09:45.113529Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4006782052,"revision":6096,"compact-revision":2967} {"level":"info","ts":"2026-08-13T01:09:59.456481Z","caller":"traceutil/trace.go:171","msg":"trace[1626956102] linearizableReadLoop","detail":"{readStateIndex:8683; appliedIndex:8682; }","duration":"389.327472ms","start":"2026-08-13T01:09:59.067122Z","end":"2026-08-13T01:09:59.45645Z","steps":["trace[1626956102] 'read index received' (duration: 389.145787ms)","trace[1626956102] 'applied index is now lower than readState.Index' (duration: 180.045µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:09:59.456687Z","caller":"traceutil/trace.go:171","msg":"trace[558936660] transaction","detail":"{read_only:false; response_revision:8383; number_of_response:1; }","duration":"390.734266ms","start":"2026-08-13T01:09:59.065916Z","end":"2026-08-13T01:09:59.45665Z","steps":["trace[558936660] 'process raft request' (duration: 390.330066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.456823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.68469ms","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-08-13T01:09:59.456882Z","caller":"traceutil/trace.go:171","msg":"trace[1769137665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8383; }","duration":"389.786973ms","start":"2026-08-13T01:09:59.067074Z","end":"2026-08-13T01:09:59.456861Z","steps":["trace[1769137665] 'agreement among raft nodes before linearized reading' (duration: 389.556027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.456885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.648036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-13T01:09:59.45693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.067053Z","time spent":"389.863414ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-13T01:09:59.456965Z","caller":"traceutil/trace.go:171","msg":"trace[1381350465] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8383; }","duration":"316.78212ms","start":"2026-08-13T01:09:59.140164Z","end":"2026-08-13T01:09:59.456947Z","steps":["trace[1381350465] 'agreement among raft nodes before linearized reading' (duration: 316.527784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.456941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.065891Z","time spent":"390.92719ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:09:59.457012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:09:59.140144Z","time spent":"316.859401ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-13T01:09:59.457024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.576159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vnnc7\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-13T01:09:59.457078Z","caller":"traceutil/trace.go:171","msg":"trace[1041760767] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vnnc7; range_end:; response_count:1; response_revision:8383; }","duration":"203.651851ms","start":"2026-08-13T01:09:59.253409Z","end":"2026-08-13T01:09:59.45706Z","steps":["trace[1041760767] 'agreement among raft nodes before linearized reading' (duration: 203.556399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.456912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.656729ms","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-08-13T01:09:59.457218Z","caller":"traceutil/trace.go:171","msg":"trace[2081140332] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8383; }","duration":"253.997227ms","start":"2026-08-13T01:09:59.203201Z","end":"2026-08-13T01:09:59.457198Z","steps":["trace[2081140332] 'agreement among raft nodes before linearized reading' (duration: 253.504705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.457293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.007442ms","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-08-13T01:09:59.45732Z","caller":"traceutil/trace.go:171","msg":"trace[220683098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8383; }","duration":"244.062414ms","start":"2026-08-13T01:09:59.213249Z","end":"2026-08-13T01:09:59.457312Z","steps":["trace[220683098] 'agreement among raft nodes before linearized reading' (duration: 243.965931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.457344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.772292ms","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-08-13T01:09:59.457376Z","caller":"traceutil/trace.go:171","msg":"trace[1848150031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8383; }","duration":"264.833213ms","start":"2026-08-13T01:09:59.192533Z","end":"2026-08-13T01:09:59.457366Z","steps":["trace[1848150031] 'agreement among raft nodes before linearized reading' (duration: 264.742331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.45743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.558413ms","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-08-13T01:09:59.457474Z","caller":"traceutil/trace.go:171","msg":"trace[1622643445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8383; }","duration":"259.621835ms","start":"2026-08-13T01:09:59.197839Z","end":"2026-08-13T01:09:59.457461Z","steps":["trace[1622643445] 'agreement among raft nodes before linearized reading' (duration: 259.494852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:09:59.46072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.269009ms","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-08-13T01:09:59.461279Z","caller":"traceutil/trace.go:171","msg":"trace[1747729611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8383; }","duration":"266.786863ms","start":"2026-08-13T01:09:59.194249Z","end":"2026-08-13T01:09:59.461036Z","steps":["trace[1747729611] 'agreement among raft nodes before linearized reading' (duration: 263.516642ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:11.916606Z","caller":"traceutil/trace.go:171","msg":"trace[1673159470] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8496; }","duration":"146.497487ms","start":"2026-08-13T01:10:11.770083Z","end":"2026-08-13T01:10:11.91658Z","steps":["trace[1673159470] 'process raft request' (duration: 146.344543ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:11.916763Z","caller":"traceutil/trace.go:171","msg":"trace[665050719] transaction","detail":"{read_only:false; response_revision:8495; number_of_response:1; }","duration":"146.890689ms","start":"2026-08-13T01:10:11.769833Z","end":"2026-08-13T01:10:11.916723Z","steps":["trace[665050719] 'process raft request' (duration: 55.656897ms)","trace[665050719] 'compare' (duration: 90.76088ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:13.654542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.229213ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-13T01:10:13.654663Z","caller":"traceutil/trace.go:171","msg":"trace[861448071] linearizableReadLoop","detail":"{readStateIndex:8808; appliedIndex:8807; }","duration":"130.32291ms","start":"2026-08-13T01:10:13.524314Z","end":"2026-08-13T01:10:13.654637Z","steps":["trace[861448071] 'read index received' (duration: 47.721µs)","trace[861448071] 'applied index is now lower than readState.Index' (duration: 130.272629ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:13.655093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.771081ms","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-08-13T01:10:13.655157Z","caller":"traceutil/trace.go:171","msg":"trace[65062920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8502; }","duration":"130.879954ms","start":"2026-08-13T01:10:13.524262Z","end":"2026-08-13T01:10:13.655142Z","steps":["trace[65062920] 'agreement among raft nodes before linearized reading' (duration: 130.660178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:13.655149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.27712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T01:10:13.655223Z","caller":"traceutil/trace.go:171","msg":"trace[504549964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8502; }","duration":"106.395383ms","start":"2026-08-13T01:10:13.548806Z","end":"2026-08-13T01:10:13.655201Z","steps":["trace[504549964] 'agreement among raft nodes before linearized reading' (duration: 106.176478ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:53.940385Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f5.wal"} {"level":"info","ts":"2026-08-13T01:11:25.063785Z","caller":"traceutil/trace.go:171","msg":"trace[1691003535] transaction","detail":"{read_only:false; response_revision:9164; number_of_response:1; }","duration":"121.666967ms","start":"2026-08-13T01:11:24.942094Z","end":"2026-08-13T01:11:25.063761Z","steps":["trace[1691003535] 'process raft request' (duration: 121.508603ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:11:25.199389Z","caller":"traceutil/trace.go:171","msg":"trace[1267538228] transaction","detail":"{read_only:false; response_revision:9166; number_of_response:1; }","duration":"130.805342ms","start":"2026-08-13T01:11:25.068552Z","end":"2026-08-13T01:11:25.199357Z","steps":["trace[1267538228] 'process raft request' (duration: 118.51919ms)","trace[1267538228] 'compare' (duration: 12.152928ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:12:20.334324Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"92da22d3868c86fb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T01:12:20.338681Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-13T01:12:20.338766Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-13T01:14:45.021326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8260} {"level":"info","ts":"2026-08-13T01:14:45.100522Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8260,"took":"76.850701ms","hash":171374940,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":26734592,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-13T01:14:45.100657Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":171374940,"revision":8260,"compact-revision":6096} {"level":"info","ts":"2026-08-13T01:19:45.028063Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10956} {"level":"info","ts":"2026-08-13T01:19:45.107385Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10956,"took":"76.779803ms","hash":47459451,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":28008448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-13T01:19:45.10744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":47459451,"revision":10956,"compact-revision":8260} {"level":"info","ts":"2026-08-13T01:21:34.63827Z","caller":"traceutil/trace.go:171","msg":"trace[464240653] linearizableReadLoop","detail":"{readStateIndex:14176; appliedIndex:14175; }","duration":"232.655537ms","start":"2026-08-13T01:21:34.405593Z","end":"2026-08-13T01:21:34.638248Z","steps":["trace[464240653] 'read index received' (duration: 232.408851ms)","trace[464240653] 'applied index is now lower than readState.Index' (duration: 245.506µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:21:34.638348Z","caller":"traceutil/trace.go:171","msg":"trace[661405306] transaction","detail":"{read_only:false; response_revision:13702; number_of_response:1; }","duration":"347.876812ms","start":"2026-08-13T01:21:34.290424Z","end":"2026-08-13T01:21:34.638301Z","steps":["trace[661405306] 'process raft request' (duration: 347.586315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:21:34.638519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.878062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:21:34.638554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:21:34.290397Z","time spent":"348.055827ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:21:34.638589Z","caller":"traceutil/trace.go:171","msg":"trace[10366809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13702; }","duration":"233.005485ms","start":"2026-08-13T01:21:34.405548Z","end":"2026-08-13T01:21:34.638553Z","steps":["trace[10366809] 'agreement among raft nodes before linearized reading' (duration: 232.885272ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:21:43.618293Z","caller":"traceutil/trace.go:171","msg":"trace[1953138395] transaction","detail":"{read_only:false; response_revision:13870; number_of_response:1; }","duration":"145.585491ms","start":"2026-08-13T01:21:43.472674Z","end":"2026-08-13T01:21:43.618259Z","steps":["trace[1953138395] 'process raft request' (duration: 145.299434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:21:43.898629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.304423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:21:43.899018Z","caller":"traceutil/trace.go:171","msg":"trace[1408766042] transaction","detail":"{read_only:false; response_revision:13871; number_of_response:1; }","duration":"270.520676ms","start":"2026-08-13T01:21:43.628452Z","end":"2026-08-13T01:21:43.898972Z","steps":["trace[1408766042] 'process raft request' (duration: 131.791372ms)","trace[1408766042] 'compare' (duration: 138.193061ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:21:43.904354Z","caller":"traceutil/trace.go:171","msg":"trace[1655663114] linearizableReadLoop","detail":"{readStateIndex:14348; appliedIndex:14346; }","duration":"114.392596ms","start":"2026-08-13T01:21:43.789944Z","end":"2026-08-13T01:21:43.904336Z","steps":["trace[1655663114] 'read index received' (duration: 4.556602ms)","trace[1655663114] 'applied index is now lower than readState.Index' (duration: 109.834714ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:21:43.904587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.642512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-kvn9p.18cb38c67895c1db\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-08-13T01:21:43.904618Z","caller":"traceutil/trace.go:171","msg":"trace[4658674] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-kvn9p.18cb38c67895c1db; range_end:; response_count:1; response_revision:13871; }","duration":"114.723324ms","start":"2026-08-13T01:21:43.789887Z","end":"2026-08-13T01:21:43.90461Z","steps":["trace[4658674] 'agreement among raft nodes before linearized reading' (duration: 114.54722ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:24:45.035323Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12998} {"level":"info","ts":"2026-08-13T01:24:45.112228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12998,"took":"73.994874ms","hash":744347999,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":31244288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-13T01:24:45.112312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":744347999,"revision":12998,"compact-revision":10956} {"level":"warn","ts":"2026-08-13T01:29:07.739424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.464129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-13T01:29:07.739547Z","caller":"traceutil/trace.go:171","msg":"trace[1726104365] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17696; }","duration":"158.650203ms","start":"2026-08-13T01:29:07.580877Z","end":"2026-08-13T01:29:07.739527Z","steps":["trace[1726104365] 'range keys from in-memory index tree' (duration: 158.221103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:29:08.501248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.760331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-13T01:29:08.501372Z","caller":"traceutil/trace.go:171","msg":"trace[964104632] linearizableReadLoop","detail":"{readStateIndex:18294; appliedIndex:18293; }","duration":"292.033246ms","start":"2026-08-13T01:29:08.209315Z","end":"2026-08-13T01:29:08.501348Z","steps":["trace[964104632] 'read index received' (duration: 155.0213ms)","trace[964104632] 'applied index is now lower than readState.Index' (duration: 137.009996ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:29:08.501526Z","caller":"traceutil/trace.go:171","msg":"trace[358038000] transaction","detail":"{read_only:false; response_revision:17699; number_of_response:1; }","duration":"293.718264ms","start":"2026-08-13T01:29:08.207747Z","end":"2026-08-13T01:29:08.501465Z","steps":["trace[358038000] 'process raft request' (duration: 156.646297ms)","trace[358038000] 'compare' (duration: 136.383031ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:29:08.50186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.667854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T01:29:08.502297Z","caller":"traceutil/trace.go:171","msg":"trace[256965965] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:17699; }","duration":"129.088305ms","start":"2026-08-13T01:29:08.373152Z","end":"2026-08-13T01:29:08.50224Z","steps":["trace[256965965] 'agreement among raft nodes before linearized reading' (duration: 128.552833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:29:08.502096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.761383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-08-13T01:29:08.502448Z","caller":"traceutil/trace.go:171","msg":"trace[940816648] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:17699; }","duration":"293.153492ms","start":"2026-08-13T01:29:08.20927Z","end":"2026-08-13T01:29:08.502423Z","steps":["trace[940816648] 'agreement among raft nodes before linearized reading' (duration: 292.62685ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:29:45.048986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15505} {"level":"info","ts":"2026-08-13T01:29:45.145362Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15505,"took":"93.204319ms","hash":960299756,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":33034240,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-13T01:29:45.145475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":960299756,"revision":15505,"compact-revision":12998} {"level":"info","ts":"2026-08-13T01:30:02.956673Z","caller":"traceutil/trace.go:171","msg":"trace[820361456] transaction","detail":"{read_only:false; response_revision:18229; number_of_response:1; }","duration":"119.787831ms","start":"2026-08-13T01:30:02.836851Z","end":"2026-08-13T01:30:02.956639Z","steps":["trace[820361456] 'process raft request' (duration: 119.577616ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:32:30.485113Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"92da22d3868c86fb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-13T01:32:30.491089Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-13T01:32:30.491207Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-13T01:33:24.444419Z","caller":"traceutil/trace.go:171","msg":"trace[2011789656] transaction","detail":"{read_only:false; response_revision:19659; number_of_response:1; }","duration":"111.273841ms","start":"2026-08-13T01:33:24.333112Z","end":"2026-08-13T01:33:24.444385Z","steps":["trace[2011789656] 'process raft request' (duration: 110.742729ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:33:24.620357Z","caller":"traceutil/trace.go:171","msg":"trace[571401386] transaction","detail":"{read_only:false; response_revision:19660; number_of_response:1; }","duration":"124.402774ms","start":"2026-08-13T01:33:24.495926Z","end":"2026-08-13T01:33:24.620329Z","steps":["trace[571401386] 'process raft request' (duration: 124.225679ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:33:40.094614Z","caller":"traceutil/trace.go:171","msg":"trace[431659228] transaction","detail":"{read_only:false; response_revision:19738; number_of_response:1; }","duration":"132.937657ms","start":"2026-08-13T01:33:39.961642Z","end":"2026-08-13T01:33:40.09458Z","steps":["trace[431659228] 'process raft request' (duration: 132.715161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:33:40.259179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.76695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T01:33:40.259356Z","caller":"traceutil/trace.go:171","msg":"trace[682354545] linearizableReadLoop","detail":"{readStateIndex:20406; appliedIndex:20403; }","duration":"243.891468ms","start":"2026-08-13T01:33:40.015447Z","end":"2026-08-13T01:33:40.259338Z","steps":["trace[682354545] 'read index received' (duration: 78.971712ms)","trace[682354545] 'applied index is now lower than readState.Index' (duration: 164.918626ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:33:40.259743Z","caller":"traceutil/trace.go:171","msg":"trace[1508591735] transaction","detail":"{read_only:false; response_revision:19739; number_of_response:1; }","duration":"290.899748ms","start":"2026-08-13T01:33:39.968801Z","end":"2026-08-13T01:33:40.259701Z","steps":["trace[1508591735] 'process raft request' (duration: 160.519742ms)","trace[1508591735] 'compare' (duration: 129.657218ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:33:40.259774Z","caller":"traceutil/trace.go:171","msg":"trace[499002114] transaction","detail":"{read_only:false; response_revision:19740; number_of_response:1; }","duration":"288.802539ms","start":"2026-08-13T01:33:39.970937Z","end":"2026-08-13T01:33:40.259739Z","steps":["trace[499002114] 'process raft request' (duration: 288.345517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:33:40.260386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.927393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-08-13T01:33:40.260464Z","caller":"traceutil/trace.go:171","msg":"trace[1416048737] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19740; }","duration":"245.050716ms","start":"2026-08-13T01:33:40.015395Z","end":"2026-08-13T01:33:40.260446Z","steps":["trace[1416048737] 'agreement among raft nodes before linearized reading' (duration: 244.37333ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:33:46.518843Z","caller":"traceutil/trace.go:171","msg":"trace[125925410] transaction","detail":"{read_only:false; response_revision:19769; number_of_response:1; }","duration":"239.321691ms","start":"2026-08-13T01:33:46.279488Z","end":"2026-08-13T01:33:46.51881Z","steps":["trace[125925410] 'process raft request' (duration: 239.109536ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:33:46.632834Z","caller":"traceutil/trace.go:171","msg":"trace[1723178807] linearizableReadLoop","detail":"{readStateIndex:20437; appliedIndex:20435; }","duration":"230.179272ms","start":"2026-08-13T01:33:46.402628Z","end":"2026-08-13T01:33:46.632808Z","steps":["trace[1723178807] 'read index received' (duration: 115.935392ms)","trace[1723178807] 'applied index is now lower than readState.Index' (duration: 114.24273ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:33:46.632896Z","caller":"traceutil/trace.go:171","msg":"trace[1311020124] transaction","detail":"{read_only:false; response_revision:19770; number_of_response:1; }","duration":"352.597598ms","start":"2026-08-13T01:33:46.280266Z","end":"2026-08-13T01:33:46.632863Z","steps":["trace[1311020124] 'process raft request' (duration: 339.569568ms)","trace[1311020124] 'compare' (duration: 12.789603ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:33:46.632981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.342126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T01:33:46.633026Z","caller":"traceutil/trace.go:171","msg":"trace[2046698688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19770; }","duration":"230.426969ms","start":"2026-08-13T01:33:46.402588Z","end":"2026-08-13T01:33:46.633015Z","steps":["trace[2046698688] 'agreement among raft nodes before linearized reading' (duration: 230.349007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:33:46.633025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:33:46.280247Z","time spent":"352.7152ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:33:46.8563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.9829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-13T01:33:46.856403Z","caller":"traceutil/trace.go:171","msg":"trace[846672456] linearizableReadLoop","detail":"{readStateIndex:20438; appliedIndex:20437; }","duration":"214.881787ms","start":"2026-08-13T01:33:46.641504Z","end":"2026-08-13T01:33:46.856386Z","steps":["trace[846672456] 'read index received' (duration: 91.658242ms)","trace[846672456] 'applied index is now lower than readState.Index' (duration: 123.221785ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:33:46.85667Z","caller":"traceutil/trace.go:171","msg":"trace[844473759] transaction","detail":"{read_only:false; response_revision:19771; number_of_response:1; }","duration":"218.716229ms","start":"2026-08-13T01:33:46.637921Z","end":"2026-08-13T01:33:46.856637Z","steps":["trace[844473759] 'process raft request' (duration: 95.30189ms)","trace[844473759] 'compare' (duration: 122.816245ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:33:46.857062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.225052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128329"} {"level":"warn","ts":"2026-08-13T01:33:46.857098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.261769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-13T01:33:46.857156Z","caller":"traceutil/trace.go:171","msg":"trace[199604281] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:19771; }","duration":"214.351186ms","start":"2026-08-13T01:33:46.642784Z","end":"2026-08-13T01:33:46.857136Z","steps":["trace[199604281] 'agreement among raft nodes before linearized reading' (duration: 214.014057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:33:46.857168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.669106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-08-13T01:33:46.857177Z","caller":"traceutil/trace.go:171","msg":"trace[90444484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19771; }","duration":"137.360202ms","start":"2026-08-13T01:33:46.719784Z","end":"2026-08-13T01:33:46.857144Z","steps":["trace[90444484] 'agreement among raft nodes before linearized reading' (duration: 137.160227ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:33:46.857207Z","caller":"traceutil/trace.go:171","msg":"trace[1618277899] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:19771; }","duration":"215.732918ms","start":"2026-08-13T01:33:46.641463Z","end":"2026-08-13T01:33:46.857196Z","steps":["trace[1618277899] 'agreement among raft nodes before linearized reading' (duration: 215.211665ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:34:45.054788Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18046} {"level":"info","ts":"2026-08-13T01:34:45.165714Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18046,"took":"106.749552ms","hash":3979994847,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":30097408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T01:34:45.165772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3979994847,"revision":18046,"compact-revision":15505} {"level":"info","ts":"2026-08-13T01:37:24.067116Z","caller":"traceutil/trace.go:171","msg":"trace[1953681802] transaction","detail":"{read_only:false; response_revision:22134; number_of_response:1; }","duration":"110.381793ms","start":"2026-08-13T01:37:23.956708Z","end":"2026-08-13T01:37:24.06709Z","steps":["trace[1953681802] 'process raft request' (duration: 110.206638ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:37:24.120269Z","caller":"traceutil/trace.go:171","msg":"trace[303961255] linearizableReadLoop","detail":"{readStateIndex:22874; appliedIndex:22871; }","duration":"148.15547ms","start":"2026-08-13T01:37:23.972091Z","end":"2026-08-13T01:37:24.120246Z","steps":["trace[303961255] 'read index received' (duration: 94.884801ms)","trace[303961255] 'applied index is now lower than readState.Index' (duration: 53.265679ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:37:24.120368Z","caller":"traceutil/trace.go:171","msg":"trace[1465699175] transaction","detail":"{read_only:false; response_revision:22135; number_of_response:1; }","duration":"162.577886ms","start":"2026-08-13T01:37:23.957717Z","end":"2026-08-13T01:37:24.120295Z","steps":["trace[1465699175] 'process raft request' (duration: 162.35979ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:37:24.120439Z","caller":"traceutil/trace.go:171","msg":"trace[621537516] transaction","detail":"{read_only:false; response_revision:22136; number_of_response:1; }","duration":"160.588717ms","start":"2026-08-13T01:37:23.959839Z","end":"2026-08-13T01:37:24.120427Z","steps":["trace[621537516] 'process raft request' (duration: 160.358051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:37:24.120741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.833121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-13T01:37:24.120812Z","caller":"traceutil/trace.go:171","msg":"trace[1530562299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22136; }","duration":"142.915163ms","start":"2026-08-13T01:37:23.97788Z","end":"2026-08-13T01:37:24.120795Z","steps":["trace[1530562299] 'agreement among raft nodes before linearized reading' (duration: 142.718888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:37:24.120867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.760766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-13T01:37:24.120937Z","caller":"traceutil/trace.go:171","msg":"trace[232509113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22136; }","duration":"148.87338ms","start":"2026-08-13T01:37:23.97205Z","end":"2026-08-13T01:37:24.120923Z","steps":["trace[232509113] 'agreement among raft nodes before linearized reading' (duration: 148.542642ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:38:53.427362Z","caller":"traceutil/trace.go:171","msg":"trace[1451145212] linearizableReadLoop","detail":"{readStateIndex:23824; appliedIndex:23823; }","duration":"122.723926ms","start":"2026-08-13T01:38:53.304606Z","end":"2026-08-13T01:38:53.42733Z","steps":["trace[1451145212] 'read index received' (duration: 38.472705ms)","trace[1451145212] 'applied index is now lower than readState.Index' (duration: 84.249471ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:38:53.427553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.917681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-13T01:38:53.427629Z","caller":"traceutil/trace.go:171","msg":"trace[1809170396] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:23064; }","duration":"123.065585ms","start":"2026-08-13T01:38:53.304542Z","end":"2026-08-13T01:38:53.427608Z","steps":["trace[1809170396] 'agreement among raft nodes before linearized reading' (duration: 122.916171ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:38:53.427465Z","caller":"traceutil/trace.go:171","msg":"trace[596880141] transaction","detail":"{read_only:false; response_revision:23064; number_of_response:1; }","duration":"123.362772ms","start":"2026-08-13T01:38:53.304033Z","end":"2026-08-13T01:38:53.427396Z","steps":["trace[596880141] 'process raft request' (duration: 38.926877ms)","trace[596880141] 'compare' (duration: 83.968673ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:39:45.06064Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20338} {"level":"info","ts":"2026-08-13T01:39:45.169997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20338,"took":"105.516014ms","hash":2780017413,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":43220992,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-13T01:39:45.170054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2780017413,"revision":20338,"compact-revision":18046} {"level":"info","ts":"2026-08-13T01:44:45.066853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23465} {"level":"info","ts":"2026-08-13T01:44:45.196474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23465,"took":"125.697455ms","hash":2717130225,"current-db-size-bytes":59150336,"current-db-size":"59 MB","current-db-size-in-use-bytes":30134272,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-13T01:44:45.196531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2717130225,"revision":23465,"compact-revision":20338}