{"level":"warn","ts":"2026-07-14T16:52:48.54289Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:52:48.542992Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.195: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.195:2380","--initial-cluster=instance=https://199.19.213.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.195:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-14T16:52:48.543066Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:52:48.543082Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-07-14T16:52:48.543104Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T16:52:48.543714Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"]} {"level":"info","ts":"2026-07-14T16:52:48.543791Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.195:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-14T16:52:48.548014Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.006312ms"} {"level":"info","ts":"2026-07-14T16:52:48.553949Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2808ca5e53a916c4","cluster-id":"b7ef986abcd4d0b8"} {"level":"info","ts":"2026-07-14T16:52:48.554023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T16:52:48.55405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 0"} {"level":"info","ts":"2026-07-14T16:52:48.554065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2808ca5e53a916c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T16:52:48.554107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became follower at term 1"} {"level":"info","ts":"2026-07-14T16:52:48.554279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"warn","ts":"2026-07-14T16:52:48.560745Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T16:52:48.562379Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T16:52:48.563497Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-14T16:52:48.565759Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2808ca5e53a916c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T16:52:48.566316Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:52:48.566399Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:52:48.566409Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:52:48.566584Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2808ca5e53a916c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T16:52:48.567084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 switched to configuration voters=(2884778067810129604)"} {"level":"info","ts":"2026-07-14T16:52:48.567325Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","added-peer-id":"2808ca5e53a916c4","added-peer-peer-urls":["https://199.19.213.195:2380"]} {"level":"info","ts":"2026-07-14T16:52:48.572585Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T16:52:48.572893Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2808ca5e53a916c4","initial-advertise-peer-urls":["https://199.19.213.195:2380"],"listen-peer-urls":["https://199.19.213.195:2380"],"advertise-client-urls":["https://199.19.213.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T16:52:48.572981Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T16:52:48.573141Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-07-14T16:52:48.573203Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.195:2380"} {"level":"info","ts":"2026-07-14T16:52:48.655497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T16:52:48.655693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T16:52:48.655773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgPreVoteResp from 2808ca5e53a916c4 at term 1"} {"level":"info","ts":"2026-07-14T16:52:48.655807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became candidate at term 2"} {"level":"info","ts":"2026-07-14T16:52:48.655831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 received MsgVoteResp from 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-07-14T16:52:48.655882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2808ca5e53a916c4 became leader at term 2"} {"level":"info","ts":"2026-07-14T16:52:48.655934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2808ca5e53a916c4 elected leader 2808ca5e53a916c4 at term 2"} {"level":"info","ts":"2026-07-14T16:52:48.656639Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:52:48.657301Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2808ca5e53a916c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.195:2379]}","request-path":"/0/members/2808ca5e53a916c4/attributes","cluster-id":"b7ef986abcd4d0b8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T16:52:48.657394Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:52:48.657572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:52:48.657801Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T16:52:48.657874Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T16:52:48.657917Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b7ef986abcd4d0b8","local-member-id":"2808ca5e53a916c4","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:52:48.658003Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:52:48.658044Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:52:48.659934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T16:52:48.660986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.195:2379"} {"level":"info","ts":"2026-07-14T16:52:58.120855Z","caller":"traceutil/trace.go:171","msg":"trace[1943273721] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"125.228865ms","start":"2026-07-14T16:52:57.995603Z","end":"2026-07-14T16:52:58.120832Z","steps":["trace[1943273721] 'read index received' (duration: 125.02835ms)","trace[1943273721] 'applied index is now lower than readState.Index' (duration: 199.375µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:52:58.120925Z","caller":"traceutil/trace.go:171","msg":"trace[794932887] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"154.028265ms","start":"2026-07-14T16:52:57.966857Z","end":"2026-07-14T16:52:58.120885Z","steps":["trace[794932887] 'process raft request' (duration: 153.857691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:58.121218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.47473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-14T16:52:58.12151Z","caller":"traceutil/trace.go:171","msg":"trace[694674428] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:340; }","duration":"125.938961ms","start":"2026-07-14T16:52:57.99555Z","end":"2026-07-14T16:52:58.121489Z","steps":["trace[694674428] 'agreement among raft nodes before linearized reading' (duration: 125.411359ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:03.619413Z","caller":"traceutil/trace.go:171","msg":"trace[1515077977] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"138.189716ms","start":"2026-07-14T16:53:03.4812Z","end":"2026-07-14T16:53:03.61939Z","steps":["trace[1515077977] 'process raft request' (duration: 137.96307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:04.727891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.566698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:53:04.728135Z","caller":"traceutil/trace.go:171","msg":"trace[974824851] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.101238357s","start":"2026-07-14T16:53:03.626865Z","end":"2026-07-14T16:53:04.728103Z","steps":["trace[974824851] 'process raft request' (duration: 606.696106ms)","trace[974824851] 'compare' (duration: 493.385703ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:04.728845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:03.626838Z","time spent":"1.101944563s","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:53:04.728156Z","caller":"traceutil/trace.go:171","msg":"trace[967987297] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"971.825949ms","start":"2026-07-14T16:53:03.756307Z","end":"2026-07-14T16:53:04.728133Z","steps":["trace[967987297] 'process raft request' (duration: 971.727656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:04.729082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:03.75629Z","time spent":"972.762231ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:53:04.728249Z","caller":"traceutil/trace.go:171","msg":"trace[725799525] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"988.421742ms","start":"2026-07-14T16:53:03.739775Z","end":"2026-07-14T16:53:04.728197Z","steps":["trace[725799525] 'read index received' (duration: 493.872521ms)","trace[725799525] 'applied index is now lower than readState.Index' (duration: 494.544391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:04.728419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.639658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T16:53:04.729264Z","caller":"traceutil/trace.go:171","msg":"trace[293319727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"989.530879ms","start":"2026-07-14T16:53:03.739719Z","end":"2026-07-14T16:53:04.72925Z","steps":["trace[293319727] 'agreement among raft nodes before linearized reading' (duration: 988.631788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:04.729299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:03.739698Z","time spent":"989.59231ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T16:53:04.729485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.866586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-14T16:53:04.729503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.451637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T16:53:04.729537Z","caller":"traceutil/trace.go:171","msg":"trace[1570909507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"274.487768ms","start":"2026-07-14T16:53:04.455039Z","end":"2026-07-14T16:53:04.729527Z","steps":["trace[1570909507] 'agreement among raft nodes before linearized reading' (duration: 274.423187ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:04.729548Z","caller":"traceutil/trace.go:171","msg":"trace[451289542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"851.957388ms","start":"2026-07-14T16:53:03.877569Z","end":"2026-07-14T16:53:04.729527Z","steps":["trace[451289542] 'agreement among raft nodes before linearized reading' (duration: 851.859276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:04.72961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:03.877547Z","time spent":"852.03303ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T16:53:24.999039Z","caller":"traceutil/trace.go:171","msg":"trace[1464598014] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"290.744849ms","start":"2026-07-14T16:53:24.708257Z","end":"2026-07-14T16:53:24.999002Z","steps":["trace[1464598014] 'process raft request' (duration: 290.547814ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.030381Z","caller":"traceutil/trace.go:171","msg":"trace[863402469] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"314.627149ms","start":"2026-07-14T16:53:24.715732Z","end":"2026-07-14T16:53:25.030359Z","steps":["trace[863402469] 'process raft request' (duration: 314.525227ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.030371Z","caller":"traceutil/trace.go:171","msg":"trace[438507054] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"314.765684ms","start":"2026-07-14T16:53:24.715569Z","end":"2026-07-14T16:53:25.030335Z","steps":["trace[438507054] 'process raft request' (duration: 314.56152ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.030442Z","caller":"traceutil/trace.go:171","msg":"trace[1166413372] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"306.275537ms","start":"2026-07-14T16:53:24.724143Z","end":"2026-07-14T16:53:25.030418Z","steps":["trace[1166413372] 'process raft request' (duration: 306.152044ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.030513Z","caller":"traceutil/trace.go:171","msg":"trace[41613965] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"301.327299ms","start":"2026-07-14T16:53:24.72916Z","end":"2026-07-14T16:53:25.030488Z","steps":["trace[41613965] 'process raft request' (duration: 301.187616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:25.030547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:24.715533Z","time spent":"314.952397ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:53:25.030572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:24.729142Z","time spent":"301.40358ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:53:25.030562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:24.724121Z","time spent":"306.385929ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:53:25.030577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:24.715727Z","time spent":"314.717341ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T16:53:25.030654Z","caller":"traceutil/trace.go:171","msg":"trace[1014402735] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"290.811479ms","start":"2026-07-14T16:53:24.739837Z","end":"2026-07-14T16:53:25.030648Z","steps":["trace[1014402735] 'process raft request' (duration: 290.552823ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.030667Z","caller":"traceutil/trace.go:171","msg":"trace[176618104] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"290.536913ms","start":"2026-07-14T16:53:24.740119Z","end":"2026-07-14T16:53:25.030656Z","steps":["trace[176618104] 'process raft request' (duration: 290.372069ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.03085Z","caller":"traceutil/trace.go:171","msg":"trace[961803334] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"287.156369ms","start":"2026-07-14T16:53:24.743684Z","end":"2026-07-14T16:53:25.03084Z","steps":["trace[961803334] 'process raft request' (duration: 286.945334ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.031338Z","caller":"traceutil/trace.go:171","msg":"trace[1685586033] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"288.383555ms","start":"2026-07-14T16:53:24.742366Z","end":"2026-07-14T16:53:25.03075Z","steps":["trace[1685586033] 'process raft request' (duration: 288.17002ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.037336Z","caller":"traceutil/trace.go:171","msg":"trace[935329930] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"287.295981ms","start":"2026-07-14T16:53:24.749914Z","end":"2026-07-14T16:53:25.03721Z","steps":["trace[935329930] 'process raft request' (duration: 281.434253ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.039196Z","caller":"traceutil/trace.go:171","msg":"trace[491317060] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"284.758615ms","start":"2026-07-14T16:53:24.754341Z","end":"2026-07-14T16:53:25.039099Z","steps":["trace[491317060] 'process raft request' (duration: 278.885397ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.047893Z","caller":"traceutil/trace.go:171","msg":"trace[854674669] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:540; }","duration":"226.626981ms","start":"2026-07-14T16:53:24.821212Z","end":"2026-07-14T16:53:25.047839Z","steps":["trace[854674669] 'read index received' (duration: 177.623151ms)","trace[854674669] 'applied index is now lower than readState.Index' (duration: 44.983593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:25.048006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.781364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T16:53:25.048266Z","caller":"traceutil/trace.go:171","msg":"trace[301370179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"227.076931ms","start":"2026-07-14T16:53:24.821181Z","end":"2026-07-14T16:53:25.048258Z","steps":["trace[301370179] 'agreement among raft nodes before linearized reading' (duration: 222.644145ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.048026Z","caller":"traceutil/trace.go:171","msg":"trace[131330851] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"157.322084ms","start":"2026-07-14T16:53:24.886509Z","end":"2026-07-14T16:53:25.043832Z","steps":["trace[131330851] 'process raft request' (duration: 149.404421ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.252769Z","caller":"traceutil/trace.go:171","msg":"trace[1082861772] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"244.426207ms","start":"2026-07-14T16:53:25.008317Z","end":"2026-07-14T16:53:25.252743Z","steps":["trace[1082861772] 'process raft request' (duration: 244.352015ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.252793Z","caller":"traceutil/trace.go:171","msg":"trace[1546775413] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"244.485537ms","start":"2026-07-14T16:53:25.008284Z","end":"2026-07-14T16:53:25.25277Z","steps":["trace[1546775413] 'process raft request' (duration: 244.245312ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.26256Z","caller":"traceutil/trace.go:171","msg":"trace[2139705960] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"225.579645ms","start":"2026-07-14T16:53:25.036959Z","end":"2026-07-14T16:53:25.262539Z","steps":["trace[2139705960] 'process raft request' (duration: 225.402801ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.262601Z","caller":"traceutil/trace.go:171","msg":"trace[1642564654] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"222.641792ms","start":"2026-07-14T16:53:25.039939Z","end":"2026-07-14T16:53:25.262581Z","steps":["trace[1642564654] 'process raft request' (duration: 222.54413ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.262837Z","caller":"traceutil/trace.go:171","msg":"trace[1255147190] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"214.894292ms","start":"2026-07-14T16:53:25.04793Z","end":"2026-07-14T16:53:25.262825Z","steps":["trace[1255147190] 'process raft request' (duration: 214.609226ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.262942Z","caller":"traceutil/trace.go:171","msg":"trace[1275448715] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"214.77627ms","start":"2026-07-14T16:53:25.048155Z","end":"2026-07-14T16:53:25.262931Z","steps":["trace[1275448715] 'process raft request' (duration: 214.536374ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.263075Z","caller":"traceutil/trace.go:171","msg":"trace[332295318] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"214.831241ms","start":"2026-07-14T16:53:25.048229Z","end":"2026-07-14T16:53:25.263061Z","steps":["trace[332295318] 'process raft request' (duration: 214.641227ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.26326Z","caller":"traceutil/trace.go:171","msg":"trace[1693835647] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"214.840791ms","start":"2026-07-14T16:53:25.048411Z","end":"2026-07-14T16:53:25.263251Z","steps":["trace[1693835647] 'process raft request' (duration: 214.522075ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.2634Z","caller":"traceutil/trace.go:171","msg":"trace[1740888345] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"213.348248ms","start":"2026-07-14T16:53:25.050043Z","end":"2026-07-14T16:53:25.263392Z","steps":["trace[1740888345] 'process raft request' (duration: 213.173445ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.263619Z","caller":"traceutil/trace.go:171","msg":"trace[1722944083] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"208.645245ms","start":"2026-07-14T16:53:25.05496Z","end":"2026-07-14T16:53:25.263606Z","steps":["trace[1722944083] 'process raft request' (duration: 208.568153ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.263742Z","caller":"traceutil/trace.go:171","msg":"trace[1133937362] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"209.909304ms","start":"2026-07-14T16:53:25.053824Z","end":"2026-07-14T16:53:25.263733Z","steps":["trace[1133937362] 'process raft request' (duration: 209.648048ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.263794Z","caller":"traceutil/trace.go:171","msg":"trace[2037610957] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:552; }","duration":"215.522906ms","start":"2026-07-14T16:53:25.048247Z","end":"2026-07-14T16:53:25.26377Z","steps":["trace[2037610957] 'read index received' (duration: 204.348151ms)","trace[2037610957] 'applied index is now lower than readState.Index' (duration: 11.173805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:25.26388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.605151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T16:53:25.263905Z","caller":"traceutil/trace.go:171","msg":"trace[1242164770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"439.679913ms","start":"2026-07-14T16:53:24.824219Z","end":"2026-07-14T16:53:25.263899Z","steps":["trace[1242164770] 'agreement among raft nodes before linearized reading' (duration: 439.602912ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.263922Z","caller":"traceutil/trace.go:171","msg":"trace[1917170759] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"213.734127ms","start":"2026-07-14T16:53:25.050178Z","end":"2026-07-14T16:53:25.263912Z","steps":["trace[1917170759] 'process raft request' (duration: 213.124704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:25.263946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:24.824207Z","time spent":"439.731544ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T16:53:25.26403Z","caller":"traceutil/trace.go:171","msg":"trace[1837822245] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"124.58621ms","start":"2026-07-14T16:53:25.139436Z","end":"2026-07-14T16:53:25.264022Z","steps":["trace[1837822245] 'process raft request' (duration: 124.238143ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.264063Z","caller":"traceutil/trace.go:171","msg":"trace[1930872207] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"159.170156ms","start":"2026-07-14T16:53:25.104884Z","end":"2026-07-14T16:53:25.264054Z","steps":["trace[1930872207] 'process raft request' (duration: 158.691325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:25.264196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.171724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T16:53:25.264231Z","caller":"traceutil/trace.go:171","msg":"trace[141333857] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"430.237145ms","start":"2026-07-14T16:53:24.833985Z","end":"2026-07-14T16:53:25.264222Z","steps":["trace[141333857] 'agreement among raft nodes before linearized reading' (duration: 430.162864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:25.264259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:24.833974Z","time spent":"430.277726ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T16:53:25.545334Z","caller":"traceutil/trace.go:171","msg":"trace[1067024154] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"126.044002ms","start":"2026-07-14T16:53:25.419244Z","end":"2026-07-14T16:53:25.545288Z","steps":["trace[1067024154] 'process raft request' (duration: 107.721462ms)","trace[1067024154] 'compare' (duration: 18.051895ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:25.545369Z","caller":"traceutil/trace.go:171","msg":"trace[96406223] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"112.137308ms","start":"2026-07-14T16:53:25.433189Z","end":"2026-07-14T16:53:25.545326Z","steps":["trace[96406223] 'process raft request' (duration: 111.968125ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.748642Z","caller":"traceutil/trace.go:171","msg":"trace[628516897] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"147.092871ms","start":"2026-07-14T16:53:25.601514Z","end":"2026-07-14T16:53:25.748607Z","steps":["trace[628516897] 'process raft request' (duration: 126.91618ms)","trace[628516897] 'compare' (duration: 19.821613ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:25.748787Z","caller":"traceutil/trace.go:171","msg":"trace[1734074636] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"134.281192ms","start":"2026-07-14T16:53:25.614463Z","end":"2026-07-14T16:53:25.748744Z","steps":["trace[1734074636] 'process raft request' (duration: 134.037147ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:25.748956Z","caller":"traceutil/trace.go:171","msg":"trace[730565214] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"121.793249ms","start":"2026-07-14T16:53:25.627145Z","end":"2026-07-14T16:53:25.748939Z","steps":["trace[730565214] 'process raft request' (duration: 121.465932ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:26.440693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.422401ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:53:26.440801Z","caller":"traceutil/trace.go:171","msg":"trace[435605773] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"248.515918ms","start":"2026-07-14T16:53:26.192267Z","end":"2026-07-14T16:53:26.440783Z","steps":["trace[435605773] 'process raft request' (duration: 132.923993ms)","trace[435605773] 'compare' (duration: 115.293169ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:26.441182Z","caller":"traceutil/trace.go:171","msg":"trace[1399007530] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"181.384326ms","start":"2026-07-14T16:53:26.259784Z","end":"2026-07-14T16:53:26.441168Z","steps":["trace[1399007530] 'process raft request' (duration: 181.09253ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:26.441239Z","caller":"traceutil/trace.go:171","msg":"trace[1950724685] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"181.368286ms","start":"2026-07-14T16:53:26.259849Z","end":"2026-07-14T16:53:26.441217Z","steps":["trace[1950724685] 'process raft request' (duration: 181.167092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:26.710357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.684943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:53:26.710723Z","caller":"traceutil/trace.go:171","msg":"trace[1854912361] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"136.00554ms","start":"2026-07-14T16:53:26.574697Z","end":"2026-07-14T16:53:26.710702Z","steps":["trace[1854912361] 'process raft request' (duration: 135.902668ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:26.710731Z","caller":"traceutil/trace.go:171","msg":"trace[1244134574] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"182.378829ms","start":"2026-07-14T16:53:26.528327Z","end":"2026-07-14T16:53:26.710706Z","steps":["trace[1244134574] 'process raft request' (duration: 74.241575ms)","trace[1244134574] 'compare' (duration: 107.466388ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:26.71089Z","caller":"traceutil/trace.go:171","msg":"trace[525339475] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"178.506634ms","start":"2026-07-14T16:53:26.532369Z","end":"2026-07-14T16:53:26.710875Z","steps":["trace[525339475] 'process raft request' (duration: 178.113906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:28.231811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.107932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:53:28.231907Z","caller":"traceutil/trace.go:171","msg":"trace[690580508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"172.245305ms","start":"2026-07-14T16:53:28.059636Z","end":"2026-07-14T16:53:28.231882Z","steps":["trace[690580508] 'range keys from in-memory index tree' (duration: 172.042331ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:32.570257Z","caller":"traceutil/trace.go:171","msg":"trace[309215045] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"156.616966ms","start":"2026-07-14T16:53:32.413612Z","end":"2026-07-14T16:53:32.570229Z","steps":["trace[309215045] 'process raft request' (duration: 156.069574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:33.148122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.571882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:53:33.148187Z","caller":"traceutil/trace.go:171","msg":"trace[891242314] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"487.240489ms","start":"2026-07-14T16:53:32.660936Z","end":"2026-07-14T16:53:33.148176Z","steps":["trace[891242314] 'read index received' (duration: 208.511734ms)","trace[891242314] 'applied index is now lower than readState.Index' (duration: 278.728165ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:33.148276Z","caller":"traceutil/trace.go:171","msg":"trace[1198883092] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"528.069182ms","start":"2026-07-14T16:53:32.620201Z","end":"2026-07-14T16:53:33.14827Z","steps":["trace[1198883092] 'process raft request' (duration: 249.297096ms)","trace[1198883092] 'compare' (duration: 278.45389ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:33.148301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:32.62018Z","time spent":"528.109293ms","remote":"127.0.0.1:45398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:53:33.148424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.497085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zfjss\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-14T16:53:33.148438Z","caller":"traceutil/trace.go:171","msg":"trace[1803088052] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zfjss; range_end:; response_count:1; response_revision:630; }","duration":"487.554316ms","start":"2026-07-14T16:53:32.660879Z","end":"2026-07-14T16:53:33.148434Z","steps":["trace[1803088052] 'agreement among raft nodes before linearized reading' (duration: 487.502285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:33.148451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:32.660839Z","time spent":"487.610207ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-zfjss\" "} {"level":"info","ts":"2026-07-14T16:53:33.462343Z","caller":"traceutil/trace.go:171","msg":"trace[745143263] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"295.416232ms","start":"2026-07-14T16:53:33.166902Z","end":"2026-07-14T16:53:33.462318Z","steps":["trace[745143263] 'process raft request' (duration: 295.243099ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:33.462686Z","caller":"traceutil/trace.go:171","msg":"trace[225161310] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:652; }","duration":"123.042041ms","start":"2026-07-14T16:53:33.339623Z","end":"2026-07-14T16:53:33.462665Z","steps":["trace[225161310] 'read index received' (duration: 123.036001ms)","trace[225161310] 'applied index is now lower than readState.Index' (duration: 4.84µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:33.462881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.856584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T16:53:33.462881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.249445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T16:53:33.462928Z","caller":"traceutil/trace.go:171","msg":"trace[544262189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"120.928366ms","start":"2026-07-14T16:53:33.341983Z","end":"2026-07-14T16:53:33.462911Z","steps":["trace[544262189] 'agreement among raft nodes before linearized reading' (duration: 120.789103ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:33.462933Z","caller":"traceutil/trace.go:171","msg":"trace[856485442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"123.352387ms","start":"2026-07-14T16:53:33.339568Z","end":"2026-07-14T16:53:33.46292Z","steps":["trace[856485442] 'agreement among raft nodes before linearized reading' (duration: 123.188654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:33.463033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.943023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T16:53:33.463118Z","caller":"traceutil/trace.go:171","msg":"trace[1638752067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"119.058555ms","start":"2026-07-14T16:53:33.344039Z","end":"2026-07-14T16:53:33.463098Z","steps":["trace[1638752067] 'agreement among raft nodes before linearized reading' (duration: 118.755268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:34.361631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640611405572943739,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T16:53:34.437395Z","caller":"traceutil/trace.go:171","msg":"trace[1343098955] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"576.329419ms","start":"2026-07-14T16:53:33.861038Z","end":"2026-07-14T16:53:34.437367Z","steps":["trace[1343098955] 'read index received' (duration: 564.833085ms)","trace[1343098955] 'applied index is now lower than readState.Index' (duration: 11.495194ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:34.437498Z","caller":"traceutil/trace.go:171","msg":"trace[697324958] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"878.71426ms","start":"2026-07-14T16:53:33.558752Z","end":"2026-07-14T16:53:34.437466Z","steps":["trace[697324958] 'process raft request' (duration: 867.575593ms)","trace[697324958] 'compare' (duration: 10.203058ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:34.437552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.428906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:53:34.437585Z","caller":"traceutil/trace.go:171","msg":"trace[816750824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"377.536528ms","start":"2026-07-14T16:53:34.060038Z","end":"2026-07-14T16:53:34.437574Z","steps":["trace[816750824] 'agreement among raft nodes before linearized reading' (duration: 377.467246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:34.437597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:33.558685Z","time spent":"878.854143ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:53:34.437616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:34.060015Z","time spent":"377.593569ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:53:34.437641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.588594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T16:53:34.437705Z","caller":"traceutil/trace.go:171","msg":"trace[424407397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"576.702307ms","start":"2026-07-14T16:53:33.860986Z","end":"2026-07-14T16:53:34.437688Z","steps":["trace[424407397] 'agreement among raft nodes before linearized reading' (duration: 576.506093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:34.437737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:33.860966Z","time spent":"576.764768ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T16:53:36.009663Z","caller":"traceutil/trace.go:171","msg":"trace[1721143501] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"173.275339ms","start":"2026-07-14T16:53:35.836366Z","end":"2026-07-14T16:53:36.009641Z","steps":["trace[1721143501] 'process raft request' (duration: 173.173727ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:36.020365Z","caller":"traceutil/trace.go:171","msg":"trace[259000271] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"122.742498ms","start":"2026-07-14T16:53:35.897596Z","end":"2026-07-14T16:53:36.020338Z","steps":["trace[259000271] 'process raft request' (duration: 122.158376ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:45.981078Z","caller":"traceutil/trace.go:171","msg":"trace[459151749] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"220.754621ms","start":"2026-07-14T16:53:45.760289Z","end":"2026-07-14T16:53:45.981043Z","steps":["trace[459151749] 'process raft request' (duration: 220.543496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:48.885271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.513016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gdwhk\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-14T16:53:48.88536Z","caller":"traceutil/trace.go:171","msg":"trace[516433071] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gdwhk; range_end:; response_count:1; response_revision:799; }","duration":"121.640659ms","start":"2026-07-14T16:53:48.763696Z","end":"2026-07-14T16:53:48.885337Z","steps":["trace[516433071] 'range keys from in-memory index tree' (duration: 121.369173ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:49.394433Z","caller":"traceutil/trace.go:171","msg":"trace[1686886807] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"334.167672ms","start":"2026-07-14T16:53:49.06024Z","end":"2026-07-14T16:53:49.394407Z","steps":["trace[1686886807] 'read index received' (duration: 333.898816ms)","trace[1686886807] 'applied index is now lower than readState.Index' (duration: 267.906µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:49.39456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.309364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:53:49.394592Z","caller":"traceutil/trace.go:171","msg":"trace[174251261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"334.395826ms","start":"2026-07-14T16:53:49.060188Z","end":"2026-07-14T16:53:49.394583Z","steps":["trace[174251261] 'agreement among raft nodes before linearized reading' (duration: 334.325585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:49.394626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:49.060167Z","time spent":"334.452027ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T16:53:49.39483Z","caller":"traceutil/trace.go:171","msg":"trace[1197673568] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"501.976353ms","start":"2026-07-14T16:53:48.892816Z","end":"2026-07-14T16:53:49.394792Z","steps":["trace[1197673568] 'process raft request' (duration: 501.449693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:49.394977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:48.892794Z","time spent":"502.106376ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:54:19.048541Z","caller":"traceutil/trace.go:171","msg":"trace[1034029943] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"358.740775ms","start":"2026-07-14T16:54:18.689759Z","end":"2026-07-14T16:54:19.0485Z","steps":["trace[1034029943] 'process raft request' (duration: 358.47581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:54:19.04884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:54:18.689742Z","time spent":"358.94211ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:04.999896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.990416ms","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-07-14T16:55:05.000015Z","caller":"traceutil/trace.go:171","msg":"trace[952046679] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1853; }","duration":"206.19108ms","start":"2026-07-14T16:55:04.793795Z","end":"2026-07-14T16:55:04.999987Z","steps":["trace[952046679] 'range keys from in-memory index tree' (duration: 205.69892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:05.450077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.655991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T16:55:05.45026Z","caller":"traceutil/trace.go:171","msg":"trace[214676103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1854; }","duration":"110.935946ms","start":"2026-07-14T16:55:05.339295Z","end":"2026-07-14T16:55:05.450231Z","steps":["trace[214676103] 'range keys from in-memory index tree' (duration: 110.368415ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:05.650187Z","caller":"traceutil/trace.go:171","msg":"trace[1293117524] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"195.039872ms","start":"2026-07-14T16:55:05.455106Z","end":"2026-07-14T16:55:05.650146Z","steps":["trace[1293117524] 'process raft request' (duration: 194.870399ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:39.941203Z","caller":"traceutil/trace.go:171","msg":"trace[227282103] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"128.914752ms","start":"2026-07-14T16:55:39.812245Z","end":"2026-07-14T16:55:39.941159Z","steps":["trace[227282103] 'process raft request' (duration: 128.739187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.348808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.414563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:55:40.348892Z","caller":"traceutil/trace.go:171","msg":"trace[1361385390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2097; }","duration":"287.556836ms","start":"2026-07-14T16:55:40.061315Z","end":"2026-07-14T16:55:40.348872Z","steps":["trace[1361385390] 'range keys from in-memory index tree' (duration: 287.32756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.636371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640611405572946692,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T16:55:40.645133Z","caller":"traceutil/trace.go:171","msg":"trace[648917289] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"558.610881ms","start":"2026-07-14T16:55:40.086493Z","end":"2026-07-14T16:55:40.645104Z","steps":["trace[648917289] 'process raft request' (duration: 558.428286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.645315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.086466Z","time spent":"558.756865ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:40.880013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.138412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:55:40.880392Z","caller":"traceutil/trace.go:171","msg":"trace[1255478375] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"618.261981ms","start":"2026-07-14T16:55:40.262107Z","end":"2026-07-14T16:55:40.880369Z","steps":["trace[1255478375] 'process raft request' (duration: 451.668507ms)","trace[1255478375] 'compare' (duration: 165.600909ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:55:40.880474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.26209Z","time spent":"618.348244ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:55:40.881625Z","caller":"traceutil/trace.go:171","msg":"trace[2083876109] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"457.297671ms","start":"2026-07-14T16:55:40.424253Z","end":"2026-07-14T16:55:40.881551Z","steps":["trace[2083876109] 'process raft request' (duration: 455.866725ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:40.881657Z","caller":"traceutil/trace.go:171","msg":"trace[1318280411] linearizableReadLoop","detail":"{readStateIndex:2178; appliedIndex:2174; }","duration":"746.243842ms","start":"2026-07-14T16:55:40.135364Z","end":"2026-07-14T16:55:40.881608Z","steps":["trace[1318280411] 'read index received' (duration: 509.593903ms)","trace[1318280411] 'applied index is now lower than readState.Index' (duration: 236.648069ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:55:40.881963Z","caller":"traceutil/trace.go:171","msg":"trace[506708123] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"329.536995ms","start":"2026-07-14T16:55:40.552406Z","end":"2026-07-14T16:55:40.881943Z","steps":["trace[506708123] 'process raft request' (duration: 329.012101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.881743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.424233Z","time spent":"457.461614ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:55:40.882254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.552375Z","time spent":"329.637378ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:40.882262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.443291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T16:55:40.882335Z","caller":"traceutil/trace.go:171","msg":"trace[2145337443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2101; }","duration":"743.545564ms","start":"2026-07-14T16:55:40.13877Z","end":"2026-07-14T16:55:40.882315Z","steps":["trace[2145337443] 'agreement among raft nodes before linearized reading' (duration: 743.314598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.882369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.002272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-14T16:55:40.88239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.13876Z","time spent":"743.618325ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T16:55:40.882435Z","caller":"traceutil/trace.go:171","msg":"trace[76370913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2101; }","duration":"747.105734ms","start":"2026-07-14T16:55:40.135312Z","end":"2026-07-14T16:55:40.882418Z","steps":["trace[76370913] 'agreement among raft nodes before linearized reading' (duration: 746.898709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.882491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.135291Z","time spent":"747.187506ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T16:55:40.882623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.713895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T16:55:40.882661Z","caller":"traceutil/trace.go:171","msg":"trace[329755201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2101; }","duration":"582.772547ms","start":"2026-07-14T16:55:40.299878Z","end":"2026-07-14T16:55:40.882651Z","steps":["trace[329755201] 'agreement among raft nodes before linearized reading' (duration: 582.677985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.882693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.299867Z","time spent":"582.814218ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T16:55:40.882448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.185633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:55:40.882913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.522961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-07-14T16:55:40.882976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.065167ms","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-07-14T16:55:40.882982Z","caller":"traceutil/trace.go:171","msg":"trace[1443471702] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2101; }","duration":"418.624884ms","start":"2026-07-14T16:55:40.464338Z","end":"2026-07-14T16:55:40.882963Z","steps":["trace[1443471702] 'agreement among raft nodes before linearized reading' (duration: 418.48071ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:40.88301Z","caller":"traceutil/trace.go:171","msg":"trace[72988289] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2101; }","duration":"640.128239ms","start":"2026-07-14T16:55:40.24287Z","end":"2026-07-14T16:55:40.882998Z","steps":["trace[72988289] 'agreement among raft nodes before linearized reading' (duration: 640.017706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.883013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.279616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-07-14T16:55:40.883039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.242856Z","time spent":"640.17726ms","remote":"127.0.0.1:40420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T16:55:40.883035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.464321Z","time spent":"418.702358ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T16:55:40.883053Z","caller":"traceutil/trace.go:171","msg":"trace[1838809104] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2101; }","duration":"188.356398ms","start":"2026-07-14T16:55:40.694684Z","end":"2026-07-14T16:55:40.883041Z","steps":["trace[1838809104] 'agreement among raft nodes before linearized reading' (duration: 188.128692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.883137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.165869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T16:55:40.883205Z","caller":"traceutil/trace.go:171","msg":"trace[1772496339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2101; }","duration":"744.279782ms","start":"2026-07-14T16:55:40.138909Z","end":"2026-07-14T16:55:40.883189Z","steps":["trace[1772496339] 'agreement among raft nodes before linearized reading' (duration: 744.121678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.883241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.138884Z","time spent":"744.350444ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T16:55:40.882925Z","caller":"traceutil/trace.go:171","msg":"trace[1269120166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2101; }","duration":"531.968903ms","start":"2026-07-14T16:55:40.350939Z","end":"2026-07-14T16:55:40.882907Z","steps":["trace[1269120166] 'agreement among raft nodes before linearized reading' (duration: 531.179903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:40.883562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.35092Z","time spent":"532.62591ms","remote":"127.0.0.1:45066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:55:41.424628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.224467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:55:41.424914Z","caller":"traceutil/trace.go:171","msg":"trace[1561053145] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2179; }","duration":"253.773551ms","start":"2026-07-14T16:55:41.171122Z","end":"2026-07-14T16:55:41.424896Z","steps":["trace[1561053145] 'read index received' (duration: 41.581µs)","trace[1561053145] 'applied index is now lower than readState.Index' (duration: 253.73107ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:55:41.42499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.873323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:55:41.425014Z","caller":"traceutil/trace.go:171","msg":"trace[11372385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"253.935765ms","start":"2026-07-14T16:55:41.171069Z","end":"2026-07-14T16:55:41.425005Z","steps":["trace[11372385] 'agreement among raft nodes before linearized reading' (duration: 253.876573ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:41.4253Z","caller":"traceutil/trace.go:171","msg":"trace[1025275588] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"536.25628ms","start":"2026-07-14T16:55:40.889029Z","end":"2026-07-14T16:55:41.425285Z","steps":["trace[1025275588] 'process raft request' (duration: 96.292263ms)","trace[1025275588] 'compare' (duration: 438.982361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:55:41.425415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.889015Z","time spent":"536.311581ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:55:41.425609Z","caller":"traceutil/trace.go:171","msg":"trace[533475170] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"535.491321ms","start":"2026-07-14T16:55:40.890074Z","end":"2026-07-14T16:55:41.425566Z","steps":["trace[533475170] 'process raft request' (duration: 534.782592ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:41.425668Z","caller":"traceutil/trace.go:171","msg":"trace[1438424133] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"536.582188ms","start":"2026-07-14T16:55:40.889073Z","end":"2026-07-14T16:55:41.425655Z","steps":["trace[1438424133] 'process raft request' (duration: 535.721425ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:41.425679Z","caller":"traceutil/trace.go:171","msg":"trace[2134570290] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"536.595798ms","start":"2026-07-14T16:55:40.889052Z","end":"2026-07-14T16:55:41.425648Z","steps":["trace[2134570290] 'process raft request' (duration: 535.692304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:41.425732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.889068Z","time spent":"536.632779ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:41.425781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.889037Z","time spent":"536.68068ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:41.425788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.890062Z","time spent":"535.635054ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:55:41.425453Z","caller":"traceutil/trace.go:171","msg":"trace[782617413] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"536.300681ms","start":"2026-07-14T16:55:40.8891Z","end":"2026-07-14T16:55:41.425401Z","steps":["trace[782617413] 'process raft request' (duration: 535.724945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:41.426393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:40.889082Z","time spent":"536.906165ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:55:42.250462Z","caller":"traceutil/trace.go:171","msg":"trace[2039219790] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"264.454719ms","start":"2026-07-14T16:55:41.985975Z","end":"2026-07-14T16:55:42.250429Z","steps":["trace[2039219790] 'read index received' (duration: 264.150421ms)","trace[2039219790] 'applied index is now lower than readState.Index' (duration: 303.118µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:55:42.250567Z","caller":"traceutil/trace.go:171","msg":"trace[1349985228] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"299.11862ms","start":"2026-07-14T16:55:41.951414Z","end":"2026-07-14T16:55:42.250532Z","steps":["trace[1349985228] 'process raft request' (duration: 298.821703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:42.250673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.777639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:55:42.25073Z","caller":"traceutil/trace.go:171","msg":"trace[1121141908] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2108; }","duration":"242.890532ms","start":"2026-07-14T16:55:42.007824Z","end":"2026-07-14T16:55:42.250714Z","steps":["trace[1121141908] 'agreement among raft nodes before linearized reading' (duration: 242.771869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:42.250734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.741306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T16:55:42.250818Z","caller":"traceutil/trace.go:171","msg":"trace[835542097] range","detail":"{range_begin:/registry/masterleases/199.19.213.195; range_end:; response_count:1; response_revision:2108; }","duration":"264.87882ms","start":"2026-07-14T16:55:41.985923Z","end":"2026-07-14T16:55:42.250802Z","steps":["trace[835542097] 'agreement among raft nodes before linearized reading' (duration: 264.629194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:42.250937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.838511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:55:42.251065Z","caller":"traceutil/trace.go:171","msg":"trace[968808089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"191.021696ms","start":"2026-07-14T16:55:42.060021Z","end":"2026-07-14T16:55:42.251043Z","steps":["trace[968808089] 'agreement among raft nodes before linearized reading' (duration: 190.80394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:42.68689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.739258ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-14T16:55:42.687407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:42.252468Z","time spent":"434.931092ms","remote":"127.0.0.1:45086","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-14T16:55:42.68765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.230312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-14T16:55:42.687741Z","caller":"traceutil/trace.go:171","msg":"trace[234460910] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2108; }","duration":"359.387625ms","start":"2026-07-14T16:55:42.328331Z","end":"2026-07-14T16:55:42.687719Z","steps":["trace[234460910] 'agreement among raft nodes before linearized reading' (duration: 359.132759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:42.687787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:42.32829Z","time spent":"359.488058ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-14T16:55:42.688134Z","caller":"traceutil/trace.go:171","msg":"trace[36066064] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"358.943835ms","start":"2026-07-14T16:55:42.328402Z","end":"2026-07-14T16:55:42.687346Z","steps":["trace[36066064] 'read index received' (duration: 169.808168ms)","trace[36066064] 'applied index is now lower than readState.Index' (duration: 189.133047ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:55:42.983907Z","caller":"traceutil/trace.go:171","msg":"trace[1277037660] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2186; }","duration":"295.633889ms","start":"2026-07-14T16:55:42.688247Z","end":"2026-07-14T16:55:42.983881Z","steps":["trace[1277037660] 'read index received' (duration: 295.473745ms)","trace[1277037660] 'applied index is now lower than readState.Index' (duration: 158.814µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:55:42.984014Z","caller":"traceutil/trace.go:171","msg":"trace[861949356] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"332.361481ms","start":"2026-07-14T16:55:42.65162Z","end":"2026-07-14T16:55:42.983982Z","steps":["trace[861949356] 'process raft request' (duration: 332.105414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:42.984082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.285532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T16:55:42.984122Z","caller":"traceutil/trace.go:171","msg":"trace[1163486044] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2109; }","duration":"465.418236ms","start":"2026-07-14T16:55:42.518691Z","end":"2026-07-14T16:55:42.984109Z","steps":["trace[1163486044] 'agreement among raft nodes before linearized reading' (duration: 465.331384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:42.984158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:42.518642Z","time spent":"465.505248ms","remote":"127.0.0.1:45242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-14T16:55:42.984161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:42.651586Z","time spent":"332.492104ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:55:43.003702Z","caller":"traceutil/trace.go:171","msg":"trace[1201302824] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"114.811852ms","start":"2026-07-14T16:55:42.888863Z","end":"2026-07-14T16:55:43.003675Z","steps":["trace[1201302824] 'process raft request' (duration: 114.702879ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:43.003721Z","caller":"traceutil/trace.go:171","msg":"trace[1881031039] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"114.7048ms","start":"2026-07-14T16:55:42.888992Z","end":"2026-07-14T16:55:43.003697Z","steps":["trace[1881031039] 'process raft request' (duration: 114.609807ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:43.003861Z","caller":"traceutil/trace.go:171","msg":"trace[1486831821] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"309.441619ms","start":"2026-07-14T16:55:42.69434Z","end":"2026-07-14T16:55:43.003781Z","steps":["trace[1486831821] 'process raft request' (duration: 309.179072ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:43.003894Z","caller":"traceutil/trace.go:171","msg":"trace[1608758218] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"314.997209ms","start":"2026-07-14T16:55:42.688848Z","end":"2026-07-14T16:55:43.003845Z","steps":["trace[1608758218] 'process raft request' (duration: 314.512067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.004139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:42.688832Z","time spent":"315.181095ms","remote":"127.0.0.1:45086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:43.004163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.393539ms","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-07-14T16:55:43.004213Z","caller":"traceutil/trace.go:171","msg":"trace[449767380] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"106.488611ms","start":"2026-07-14T16:55:42.897708Z","end":"2026-07-14T16:55:43.004197Z","steps":["trace[449767380] 'agreement among raft nodes before linearized reading' (duration: 105.943308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.004182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:42.694325Z","time spent":"309.669094ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:43.510525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1640611405572946722,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T16:55:43.634013Z","caller":"traceutil/trace.go:171","msg":"trace[2137337446] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"623.83596ms","start":"2026-07-14T16:55:43.010146Z","end":"2026-07-14T16:55:43.633982Z","steps":["trace[2137337446] 'read index received' (duration: 605.418904ms)","trace[2137337446] 'applied index is now lower than readState.Index' (duration: 18.415406ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:55:43.634035Z","caller":"traceutil/trace.go:171","msg":"trace[775116452] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"625.237555ms","start":"2026-07-14T16:55:43.008766Z","end":"2026-07-14T16:55:43.634004Z","steps":["trace[775116452] 'process raft request' (duration: 606.821859ms)","trace[775116452] 'compare' (duration: 18.002616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:55:43.634253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:43.008747Z","time spent":"625.358958ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:55:43.63428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.115227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-14T16:55:43.634345Z","caller":"traceutil/trace.go:171","msg":"trace[1073988829] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2114; }","duration":"624.2013ms","start":"2026-07-14T16:55:43.010115Z","end":"2026-07-14T16:55:43.634316Z","steps":["trace[1073988829] 'agreement among raft nodes before linearized reading' (duration: 624.022335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.634398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:43.010108Z","time spent":"624.277261ms","remote":"127.0.0.1:45330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-14T16:55:43.634861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.081371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:55:43.634911Z","caller":"traceutil/trace.go:171","msg":"trace[1983834571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2114; }","duration":"574.176743ms","start":"2026-07-14T16:55:43.060724Z","end":"2026-07-14T16:55:43.6349Z","steps":["trace[1983834571] 'agreement among raft nodes before linearized reading' (duration: 574.097201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.634941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:43.060702Z","time spent":"574.232214ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T16:55:43.635184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.531497ms","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-07-14T16:55:43.63522Z","caller":"traceutil/trace.go:171","msg":"trace[141257805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2114; }","duration":"204.597459ms","start":"2026-07-14T16:55:43.430613Z","end":"2026-07-14T16:55:43.635211Z","steps":["trace[141257805] 'agreement among raft nodes before linearized reading' (duration: 204.471216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.635174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.196669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-14T16:55:43.63521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.333322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T16:55:43.635292Z","caller":"traceutil/trace.go:171","msg":"trace[196724325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2114; }","duration":"204.356132ms","start":"2026-07-14T16:55:43.430906Z","end":"2026-07-14T16:55:43.635262Z","steps":["trace[196724325] 'agreement among raft nodes before linearized reading' (duration: 204.059575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.635432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.499066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-14T16:55:43.635303Z","caller":"traceutil/trace.go:171","msg":"trace[1452268575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2114; }","duration":"204.456715ms","start":"2026-07-14T16:55:43.430821Z","end":"2026-07-14T16:55:43.635278Z","steps":["trace[1452268575] 'agreement among raft nodes before linearized reading' (duration: 204.168168ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:43.635494Z","caller":"traceutil/trace.go:171","msg":"trace[408512962] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2114; }","duration":"204.559308ms","start":"2026-07-14T16:55:43.43092Z","end":"2026-07-14T16:55:43.635479Z","steps":["trace[408512962] 'agreement among raft nodes before linearized reading' (duration: 204.419634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.635478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.87526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c2365330dcfef0\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-14T16:55:43.635572Z","caller":"traceutil/trace.go:171","msg":"trace[517317600] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c2365330dcfef0; range_end:; response_count:1; response_revision:2114; }","duration":"302.016484ms","start":"2026-07-14T16:55:43.333529Z","end":"2026-07-14T16:55:43.635546Z","steps":["trace[517317600] 'agreement among raft nodes before linearized reading' (duration: 301.724096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:55:43.63578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:55:43.333509Z","time spent":"302.251561ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c2365330dcfef0\" "} {"level":"info","ts":"2026-07-14T16:56:36.813967Z","caller":"traceutil/trace.go:171","msg":"trace[116705357] transaction","detail":"{read_only:false; response_revision:2451; number_of_response:1; }","duration":"189.215822ms","start":"2026-07-14T16:56:36.624728Z","end":"2026-07-14T16:56:36.813944Z","steps":["trace[116705357] 'process raft request' (duration: 189.075079ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:45.757776Z","caller":"traceutil/trace.go:171","msg":"trace[1170716433] transaction","detail":"{read_only:false; response_revision:2551; number_of_response:1; }","duration":"370.244391ms","start":"2026-07-14T16:56:45.387501Z","end":"2026-07-14T16:56:45.757745Z","steps":["trace[1170716433] 'process raft request' (duration: 370.044927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:45.758019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:45.38748Z","time spent":"370.392075ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:56:45.760458Z","caller":"traceutil/trace.go:171","msg":"trace[1359210860] transaction","detail":"{read_only:false; response_revision:2552; number_of_response:1; }","duration":"131.425245ms","start":"2026-07-14T16:56:45.629006Z","end":"2026-07-14T16:56:45.760431Z","steps":["trace[1359210860] 'process raft request' (duration: 130.870753ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:45.760625Z","caller":"traceutil/trace.go:171","msg":"trace[1029510814] transaction","detail":"{read_only:false; response_revision:2553; number_of_response:1; }","duration":"116.051376ms","start":"2026-07-14T16:56:45.644534Z","end":"2026-07-14T16:56:45.760586Z","steps":["trace[1029510814] 'process raft request' (duration: 115.801621ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:45.761464Z","caller":"traceutil/trace.go:171","msg":"trace[445844107] linearizableReadLoop","detail":"{readStateIndex:2646; appliedIndex:2642; }","duration":"101.933727ms","start":"2026-07-14T16:56:45.659511Z","end":"2026-07-14T16:56:45.761445Z","steps":["trace[445844107] 'read index received' (duration: 98.150082ms)","trace[445844107] 'applied index is now lower than readState.Index' (duration: 3.782505ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:56:45.761562Z","caller":"traceutil/trace.go:171","msg":"trace[162814005] transaction","detail":"{read_only:false; response_revision:2554; number_of_response:1; }","duration":"103.946984ms","start":"2026-07-14T16:56:45.657595Z","end":"2026-07-14T16:56:45.761542Z","steps":["trace[162814005] 'process raft request' (duration: 102.839049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:45.761651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.137143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-14T16:56:45.761695Z","caller":"traceutil/trace.go:171","msg":"trace[2080580196] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2554; }","duration":"102.206785ms","start":"2026-07-14T16:56:45.659476Z","end":"2026-07-14T16:56:45.761683Z","steps":["trace[2080580196] 'agreement among raft nodes before linearized reading' (duration: 102.04362ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:18.050715Z","caller":"traceutil/trace.go:171","msg":"trace[804020824] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"173.981322ms","start":"2026-07-14T16:57:17.876707Z","end":"2026-07-14T16:57:18.050688Z","steps":["trace[804020824] 'process raft request' (duration: 173.845489ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:18.176301Z","caller":"traceutil/trace.go:171","msg":"trace[315189474] linearizableReadLoop","detail":"{readStateIndex:2830; appliedIndex:2828; }","duration":"115.206556ms","start":"2026-07-14T16:57:18.061079Z","end":"2026-07-14T16:57:18.176285Z","steps":["trace[315189474] 'read index received' (duration: 111.899854ms)","trace[315189474] 'applied index is now lower than readState.Index' (duration: 3.306032ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:57:18.176371Z","caller":"traceutil/trace.go:171","msg":"trace[279326432] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"259.522505ms","start":"2026-07-14T16:57:17.916815Z","end":"2026-07-14T16:57:18.176338Z","steps":["trace[279326432] 'process raft request' (duration: 256.220042ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:18.176442Z","caller":"traceutil/trace.go:171","msg":"trace[1603252209] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"224.870694ms","start":"2026-07-14T16:57:17.951471Z","end":"2026-07-14T16:57:18.176342Z","steps":["trace[1603252209] 'process raft request' (duration: 224.788231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:57:18.176529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.430471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:57:18.176607Z","caller":"traceutil/trace.go:171","msg":"trace[1081795518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2730; }","duration":"115.536704ms","start":"2026-07-14T16:57:18.061052Z","end":"2026-07-14T16:57:18.176588Z","steps":["trace[1081795518] 'agreement among raft nodes before linearized reading' (duration: 115.37832ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:18.452176Z","caller":"traceutil/trace.go:171","msg":"trace[74165766] transaction","detail":"{read_only:false; response_revision:2733; number_of_response:1; }","duration":"270.581352ms","start":"2026-07-14T16:57:18.181574Z","end":"2026-07-14T16:57:18.452155Z","steps":["trace[74165766] 'process raft request' (duration: 270.461699ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:18.45235Z","caller":"traceutil/trace.go:171","msg":"trace[2126775356] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"269.998828ms","start":"2026-07-14T16:57:18.182325Z","end":"2026-07-14T16:57:18.452324Z","steps":["trace[2126775356] 'process raft request' (duration: 269.884486ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:18.452372Z","caller":"traceutil/trace.go:171","msg":"trace[150961816] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"270.851118ms","start":"2026-07-14T16:57:18.181509Z","end":"2026-07-14T16:57:18.45236Z","steps":["trace[150961816] 'process raft request' (duration: 201.780242ms)","trace[150961816] 'compare' (duration: 68.482124ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:57:18.452397Z","caller":"traceutil/trace.go:171","msg":"trace[1118804715] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"270.518661ms","start":"2026-07-14T16:57:18.181862Z","end":"2026-07-14T16:57:18.45238Z","steps":["trace[1118804715] 'process raft request' (duration: 270.256775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:57:18.802037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.764141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c236670c7055a8\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-14T16:57:18.802133Z","caller":"traceutil/trace.go:171","msg":"trace[1733080695] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c236670c7055a8; range_end:; response_count:1; response_revision:2735; }","duration":"189.898894ms","start":"2026-07-14T16:57:18.612212Z","end":"2026-07-14T16:57:18.802111Z","steps":["trace[1733080695] 'range keys from in-memory index tree' (duration: 189.585217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:57:18.802421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.596886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:57:18.802522Z","caller":"traceutil/trace.go:171","msg":"trace[2140237536] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2735; }","duration":"176.81982ms","start":"2026-07-14T16:57:18.62568Z","end":"2026-07-14T16:57:18.8025Z","steps":["trace[2140237536] 'count revisions from in-memory index tree' (duration: 176.544635ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:24.293267Z","caller":"traceutil/trace.go:171","msg":"trace[1155020921] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"225.293106ms","start":"2026-07-14T16:57:24.067945Z","end":"2026-07-14T16:57:24.293239Z","steps":["trace[1155020921] 'process raft request' (duration: 225.102982ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:57:24.309328Z","caller":"traceutil/trace.go:171","msg":"trace[1491589103] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"111.309815ms","start":"2026-07-14T16:57:24.197988Z","end":"2026-07-14T16:57:24.309298Z","steps":["trace[1491589103] 'process raft request' (duration: 100.357269ms)","trace[1491589103] 'compare' (duration: 10.754722ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:57:24.309634Z","caller":"traceutil/trace.go:171","msg":"trace[1733328570] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"106.727086ms","start":"2026-07-14T16:57:24.202842Z","end":"2026-07-14T16:57:24.309569Z","steps":["trace[1733328570] 'process raft request' (duration: 106.414739ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:28.640508Z","caller":"traceutil/trace.go:171","msg":"trace[1224593968] transaction","detail":"{read_only:false; response_revision:3097; number_of_response:1; }","duration":"516.137992ms","start":"2026-07-14T16:58:28.124345Z","end":"2026-07-14T16:58:28.640483Z","steps":["trace[1224593968] 'process raft request' (duration: 515.990589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:28.640667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:58:28.124328Z","time spent":"516.256304ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:59:04.856987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.193499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:59:04.857132Z","caller":"traceutil/trace.go:171","msg":"trace[799291377] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:3291; }","duration":"179.351072ms","start":"2026-07-14T16:59:04.677753Z","end":"2026-07-14T16:59:04.857104Z","steps":["trace[799291377] 'count revisions from in-memory index tree' (duration: 179.035326ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:13.438945Z","caller":"traceutil/trace.go:171","msg":"trace[2083122021] transaction","detail":"{read_only:false; response_revision:3420; number_of_response:1; }","duration":"114.345553ms","start":"2026-07-14T16:59:13.32457Z","end":"2026-07-14T16:59:13.438915Z","steps":["trace[2083122021] 'process raft request' (duration: 30.243506ms)","trace[2083122021] 'compare' (duration: 83.693149ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:21.241022Z","caller":"traceutil/trace.go:171","msg":"trace[2094112585] transaction","detail":"{read_only:false; response_revision:3459; number_of_response:1; }","duration":"106.588201ms","start":"2026-07-14T16:59:21.134386Z","end":"2026-07-14T16:59:21.240974Z","steps":["trace[2094112585] 'process raft request' (duration: 106.253655ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:21.241108Z","caller":"traceutil/trace.go:171","msg":"trace[481318835] transaction","detail":"{read_only:false; response_revision:3460; number_of_response:1; }","duration":"106.687584ms","start":"2026-07-14T16:59:21.134399Z","end":"2026-07-14T16:59:21.241086Z","steps":["trace[481318835] 'process raft request' (duration: 106.403768ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:29.132521Z","caller":"traceutil/trace.go:171","msg":"trace[1809913307] transaction","detail":"{read_only:false; response_revision:3506; number_of_response:1; }","duration":"116.591219ms","start":"2026-07-14T16:59:29.015901Z","end":"2026-07-14T16:59:29.132492Z","steps":["trace[1809913307] 'process raft request' (duration: 116.428796ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:29.132914Z","caller":"traceutil/trace.go:171","msg":"trace[1809416925] transaction","detail":"{read_only:false; response_revision:3507; number_of_response:1; }","duration":"116.780593ms","start":"2026-07-14T16:59:29.016115Z","end":"2026-07-14T16:59:29.132896Z","steps":["trace[1809416925] 'process raft request' (duration: 116.321034ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:29.132764Z","caller":"traceutil/trace.go:171","msg":"trace[1127911083] linearizableReadLoop","detail":"{readStateIndex:3638; appliedIndex:3635; }","duration":"102.658467ms","start":"2026-07-14T16:59:29.030084Z","end":"2026-07-14T16:59:29.132742Z","steps":["trace[1127911083] 'read index received' (duration: 75.933494ms)","trace[1127911083] 'applied index is now lower than readState.Index' (duration: 26.723423ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:29.133435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.3371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T16:59:29.133508Z","caller":"traceutil/trace.go:171","msg":"trace[1292941673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3507; }","duration":"103.464082ms","start":"2026-07-14T16:59:29.030026Z","end":"2026-07-14T16:59:29.13349Z","steps":["trace[1292941673] 'agreement among raft nodes before linearized reading' (duration: 103.121855ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:33.610309Z","caller":"traceutil/trace.go:171","msg":"trace[719514183] transaction","detail":"{read_only:false; response_revision:3537; number_of_response:1; }","duration":"142.97606ms","start":"2026-07-14T16:59:33.46731Z","end":"2026-07-14T16:59:33.610286Z","steps":["trace[719514183] 'process raft request' (duration: 137.443982ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:05.615787Z","caller":"traceutil/trace.go:171","msg":"trace[402938828] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"119.954309ms","start":"2026-07-14T17:01:05.495813Z","end":"2026-07-14T17:01:05.615768Z","steps":["trace[402938828] 'process raft request' (duration: 119.826757ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:05.61586Z","caller":"traceutil/trace.go:171","msg":"trace[1962676592] linearizableReadLoop","detail":"{readStateIndex:4727; appliedIndex:4727; }","duration":"109.268158ms","start":"2026-07-14T17:01:05.506569Z","end":"2026-07-14T17:01:05.615837Z","steps":["trace[1962676592] 'read index received' (duration: 109.259888ms)","trace[1962676592] 'applied index is now lower than readState.Index' (duration: 6.84µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:05.616101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.486222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-07-14T17:01:05.616152Z","caller":"traceutil/trace.go:171","msg":"trace[1816991308] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4555; }","duration":"109.599244ms","start":"2026-07-14T17:01:05.506537Z","end":"2026-07-14T17:01:05.616136Z","steps":["trace[1816991308] 'agreement among raft nodes before linearized reading' (duration: 109.396901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:05.822424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.837849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:01:05.822584Z","caller":"traceutil/trace.go:171","msg":"trace[1885190433] linearizableReadLoop","detail":"{readStateIndex:4729; appliedIndex:4727; }","duration":"206.65908ms","start":"2026-07-14T17:01:05.615908Z","end":"2026-07-14T17:01:05.822567Z","steps":["trace[1885190433] 'read index received' (duration: 51.520846ms)","trace[1885190433] 'applied index is now lower than readState.Index' (duration: 155.137364ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:05.822824Z","caller":"traceutil/trace.go:171","msg":"trace[55338887] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"222.676003ms","start":"2026-07-14T17:01:05.600134Z","end":"2026-07-14T17:01:05.82281Z","steps":["trace[55338887] 'process raft request' (duration: 67.362485ms)","trace[55338887] 'compare' (duration: 154.533524ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:05.822999Z","caller":"traceutil/trace.go:171","msg":"trace[1479824213] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"222.785794ms","start":"2026-07-14T17:01:05.600204Z","end":"2026-07-14T17:01:05.82299Z","steps":["trace[1479824213] 'process raft request' (duration: 222.310726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:05.823145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.950047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:05.823181Z","caller":"traceutil/trace.go:171","msg":"trace[1665480427] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4557; }","duration":"279.022949ms","start":"2026-07-14T17:01:05.54415Z","end":"2026-07-14T17:01:05.823173Z","steps":["trace[1665480427] 'agreement among raft nodes before linearized reading' (duration: 278.963078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:05.823379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.468374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-147b591f-5a32-43a1-a301-9fff19e73d0a\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-14T17:01:05.823414Z","caller":"traceutil/trace.go:171","msg":"trace[1968045056] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-147b591f-5a32-43a1-a301-9fff19e73d0a; range_end:; response_count:1; response_revision:4557; }","duration":"189.523925ms","start":"2026-07-14T17:01:05.633882Z","end":"2026-07-14T17:01:05.823406Z","steps":["trace[1968045056] 'agreement among raft nodes before linearized reading' (duration: 189.417993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:05.823855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.003292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-14T17:01:05.823891Z","caller":"traceutil/trace.go:171","msg":"trace[665258555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4557; }","duration":"110.083143ms","start":"2026-07-14T17:01:05.7138Z","end":"2026-07-14T17:01:05.823883Z","steps":["trace[665258555] 'agreement among raft nodes before linearized reading' (duration: 110.000841ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:06.035325Z","caller":"traceutil/trace.go:171","msg":"trace[1093108254] linearizableReadLoop","detail":"{readStateIndex:4736; appliedIndex:4733; }","duration":"121.193953ms","start":"2026-07-14T17:01:05.914109Z","end":"2026-07-14T17:01:06.035303Z","steps":["trace[1093108254] 'read index received' (duration: 121.02804ms)","trace[1093108254] 'applied index is now lower than readState.Index' (duration: 165.043µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:06.035405Z","caller":"traceutil/trace.go:171","msg":"trace[1920753462] transaction","detail":"{read_only:false; response_revision:4564; number_of_response:1; }","duration":"124.24713ms","start":"2026-07-14T17:01:05.911126Z","end":"2026-07-14T17:01:06.035373Z","steps":["trace[1920753462] 'process raft request' (duration: 124.133728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:06.035547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.407677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-07-14T17:01:06.035768Z","caller":"traceutil/trace.go:171","msg":"trace[1046784890] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4564; }","duration":"121.647151ms","start":"2026-07-14T17:01:05.914098Z","end":"2026-07-14T17:01:06.035745Z","steps":["trace[1046784890] 'agreement among raft nodes before linearized reading' (duration: 121.318805ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:06.035603Z","caller":"traceutil/trace.go:171","msg":"trace[178502390] transaction","detail":"{read_only:false; response_revision:4562; number_of_response:1; }","duration":"129.668013ms","start":"2026-07-14T17:01:05.905921Z","end":"2026-07-14T17:01:06.035589Z","steps":["trace[178502390] 'process raft request' (duration: 129.187974ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:06.036224Z","caller":"traceutil/trace.go:171","msg":"trace[763545471] transaction","detail":"{read_only:false; response_revision:4563; number_of_response:1; }","duration":"130.169542ms","start":"2026-07-14T17:01:05.90601Z","end":"2026-07-14T17:01:06.036179Z","steps":["trace[763545471] 'process raft request' (duration: 129.221805ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:08.735988Z","caller":"traceutil/trace.go:171","msg":"trace[818864584] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"240.09986ms","start":"2026-07-14T17:01:08.495868Z","end":"2026-07-14T17:01:08.735968Z","steps":["trace[818864584] 'process raft request' (duration: 239.983777ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:11.007694Z","caller":"traceutil/trace.go:171","msg":"trace[605720618] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4642; }","duration":"106.660076ms","start":"2026-07-14T17:01:10.900999Z","end":"2026-07-14T17:01:11.007659Z","steps":["trace[605720618] 'process raft request' (duration: 26.96293ms)","trace[605720618] 'compare' (duration: 79.578684ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:13.60286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.05775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:01:13.602945Z","caller":"traceutil/trace.go:171","msg":"trace[814432581] linearizableReadLoop","detail":"{readStateIndex:4909; appliedIndex:4908; }","duration":"543.179229ms","start":"2026-07-14T17:01:13.059753Z","end":"2026-07-14T17:01:13.602932Z","steps":["trace[814432581] 'read index received' (duration: 235.896575ms)","trace[814432581] 'applied index is now lower than readState.Index' (duration: 307.281504ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:13.602977Z","caller":"traceutil/trace.go:171","msg":"trace[658867863] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"576.950916ms","start":"2026-07-14T17:01:13.025997Z","end":"2026-07-14T17:01:13.602948Z","steps":["trace[658867863] 'process raft request' (duration: 269.644282ms)","trace[658867863] 'compare' (duration: 306.818625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:13.603026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.274132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:13.603046Z","caller":"traceutil/trace.go:171","msg":"trace[1094700632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4730; }","duration":"543.325303ms","start":"2026-07-14T17:01:13.059715Z","end":"2026-07-14T17:01:13.60304Z","steps":["trace[1094700632] 'agreement among raft nodes before linearized reading' (duration: 543.280202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:13.603067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:13.059697Z","time spent":"543.365504ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:01:13.603092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:13.025971Z","time spent":"577.05306ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:13.603308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.137611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c64bb87-b27f-4913-b7a3-3aa8c5d75caa\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-07-14T17:01:13.603381Z","caller":"traceutil/trace.go:171","msg":"trace[1676167099] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8c64bb87-b27f-4913-b7a3-3aa8c5d75caa; range_end:; response_count:1; response_revision:4730; }","duration":"523.225243ms","start":"2026-07-14T17:01:13.080136Z","end":"2026-07-14T17:01:13.603361Z","steps":["trace[1676167099] 'agreement among raft nodes before linearized reading' (duration: 523.019279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:13.603878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:13.080071Z","time spent":"523.790054ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3783,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8c64bb87-b27f-4913-b7a3-3aa8c5d75caa\" "} {"level":"info","ts":"2026-07-14T17:01:13.831683Z","caller":"traceutil/trace.go:171","msg":"trace[688492319] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"195.484283ms","start":"2026-07-14T17:01:13.636179Z","end":"2026-07-14T17:01:13.831663Z","steps":["trace[688492319] 'process raft request' (duration: 129.337153ms)","trace[688492319] 'compare' (duration: 66.050298ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:14.386433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.303441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:14.386511Z","caller":"traceutil/trace.go:171","msg":"trace[589321772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4737; }","duration":"326.417984ms","start":"2026-07-14T17:01:14.060075Z","end":"2026-07-14T17:01:14.386493Z","steps":["trace[589321772] 'range keys from in-memory index tree' (duration: 326.254861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:14.386553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:14.060067Z","time spent":"326.474565ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:01:14.387081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.927497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:01:14.387214Z","caller":"traceutil/trace.go:171","msg":"trace[2099812107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4737; }","duration":"279.089789ms","start":"2026-07-14T17:01:14.108077Z","end":"2026-07-14T17:01:14.387167Z","steps":["trace[2099812107] 'range keys from in-memory index tree' (duration: 278.363406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:14.387201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.80142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T17:01:14.387292Z","caller":"traceutil/trace.go:171","msg":"trace[70240854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4737; }","duration":"210.932923ms","start":"2026-07-14T17:01:14.176336Z","end":"2026-07-14T17:01:14.387269Z","steps":["trace[70240854] 'range keys from in-memory index tree' (duration: 210.191899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:14.387505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.159004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-07-14T17:01:14.38759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.504973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:01:14.387648Z","caller":"traceutil/trace.go:171","msg":"trace[25007819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4737; }","duration":"266.615006ms","start":"2026-07-14T17:01:14.121018Z","end":"2026-07-14T17:01:14.387633Z","steps":["trace[25007819] 'range keys from in-memory index tree' (duration: 265.055755ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:14.387677Z","caller":"traceutil/trace.go:171","msg":"trace[1805746859] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4737; }","duration":"331.303877ms","start":"2026-07-14T17:01:14.056297Z","end":"2026-07-14T17:01:14.387601Z","steps":["trace[1805746859] 'range keys from in-memory index tree' (duration: 330.235476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:14.387781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:14.05628Z","time spent":"331.48564ms","remote":"127.0.0.1:40206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-07-14T17:01:14.441697Z","caller":"traceutil/trace.go:171","msg":"trace[310332115] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"105.672435ms","start":"2026-07-14T17:01:14.335993Z","end":"2026-07-14T17:01:14.441665Z","steps":["trace[310332115] 'process raft request' (duration: 105.454211ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:15.830589Z","caller":"traceutil/trace.go:171","msg":"trace[1713471936] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"134.199634ms","start":"2026-07-14T17:01:15.696353Z","end":"2026-07-14T17:01:15.830553Z","steps":["trace[1713471936] 'process raft request' (duration: 134.041401ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:18.123195Z","caller":"traceutil/trace.go:171","msg":"trace[708006402] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"137.688768ms","start":"2026-07-14T17:01:17.98548Z","end":"2026-07-14T17:01:18.123169Z","steps":["trace[708006402] 'process raft request' (duration: 137.520205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:18.123483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.904236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-14T17:01:18.123521Z","caller":"traceutil/trace.go:171","msg":"trace[1042822272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4804; }","duration":"134.010679ms","start":"2026-07-14T17:01:17.989504Z","end":"2026-07-14T17:01:18.123515Z","steps":["trace[1042822272] 'agreement among raft nodes before linearized reading' (duration: 133.824375ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:18.123286Z","caller":"traceutil/trace.go:171","msg":"trace[1972507408] linearizableReadLoop","detail":"{readStateIndex:4984; appliedIndex:4984; }","duration":"133.695033ms","start":"2026-07-14T17:01:17.98956Z","end":"2026-07-14T17:01:18.123255Z","steps":["trace[1972507408] 'read index received' (duration: 133.679403ms)","trace[1972507408] 'applied index is now lower than readState.Index' (duration: 13.27µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:18.129582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.295702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-14T17:01:18.129637Z","caller":"traceutil/trace.go:171","msg":"trace[1476122260] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4805; }","duration":"108.380054ms","start":"2026-07-14T17:01:18.021243Z","end":"2026-07-14T17:01:18.129623Z","steps":["trace[1476122260] 'agreement among raft nodes before linearized reading' (duration: 107.797433ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:18.350465Z","caller":"traceutil/trace.go:171","msg":"trace[367196826] linearizableReadLoop","detail":"{readStateIndex:4989; appliedIndex:4988; }","duration":"180.540056ms","start":"2026-07-14T17:01:18.169903Z","end":"2026-07-14T17:01:18.350443Z","steps":["trace[367196826] 'read index received' (duration: 179.258523ms)","trace[367196826] 'applied index is now lower than readState.Index' (duration: 1.280483ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:18.351181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.265679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-14T17:01:18.351248Z","caller":"traceutil/trace.go:171","msg":"trace[576317883] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4809; }","duration":"181.378952ms","start":"2026-07-14T17:01:18.169852Z","end":"2026-07-14T17:01:18.351231Z","steps":["trace[576317883] 'agreement among raft nodes before linearized reading' (duration: 181.052216ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:18.351421Z","caller":"traceutil/trace.go:171","msg":"trace[856970273] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"201.859819ms","start":"2026-07-14T17:01:18.149552Z","end":"2026-07-14T17:01:18.351412Z","steps":["trace[856970273] 'process raft request' (duration: 199.616627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:18.351498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.759441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:18.35159Z","caller":"traceutil/trace.go:171","msg":"trace[205849077] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:4809; }","duration":"101.919133ms","start":"2026-07-14T17:01:18.249652Z","end":"2026-07-14T17:01:18.351571Z","steps":["trace[205849077] 'agreement among raft nodes before linearized reading' (duration: 101.725409ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:28.008915Z","caller":"traceutil/trace.go:171","msg":"trace[1350105741] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"133.810581ms","start":"2026-07-14T17:01:27.875078Z","end":"2026-07-14T17:01:28.008888Z","steps":["trace[1350105741] 'process raft request' (duration: 133.659148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.732995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.678675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:01:28.733183Z","caller":"traceutil/trace.go:171","msg":"trace[1599660236] linearizableReadLoop","detail":"{readStateIndex:5104; appliedIndex:5102; }","duration":"541.6429ms","start":"2026-07-14T17:01:28.191527Z","end":"2026-07-14T17:01:28.73317Z","steps":["trace[1599660236] 'read index received' (duration: 150.587286ms)","trace[1599660236] 'applied index is now lower than readState.Index' (duration: 391.054784ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:28.733371Z","caller":"traceutil/trace.go:171","msg":"trace[1434993584] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"830.879236ms","start":"2026-07-14T17:01:27.902483Z","end":"2026-07-14T17:01:28.733362Z","steps":["trace[1434993584] 'process raft request' (duration: 439.723571ms)","trace[1434993584] 'compare' (duration: 390.176986ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:28.733625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:27.902465Z","time spent":"830.938468ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:28.73367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.598649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T17:01:28.733735Z","caller":"traceutil/trace.go:171","msg":"trace[909147364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4919; }","duration":"215.718882ms","start":"2026-07-14T17:01:28.517999Z","end":"2026-07-14T17:01:28.733718Z","steps":["trace[909147364] 'agreement among raft nodes before linearized reading' (duration: 215.493238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.733778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.317328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T17:01:28.733809Z","caller":"traceutil/trace.go:171","msg":"trace[70579570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4919; }","duration":"537.370688ms","start":"2026-07-14T17:01:28.19643Z","end":"2026-07-14T17:01:28.733801Z","steps":["trace[70579570] 'agreement among raft nodes before linearized reading' (duration: 537.056652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.733857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:28.19642Z","time spent":"537.43107ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:01:28.733868Z","caller":"traceutil/trace.go:171","msg":"trace[2037306126] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"762.584609ms","start":"2026-07-14T17:01:27.971271Z","end":"2026-07-14T17:01:28.733855Z","steps":["trace[2037306126] 'process raft request' (duration: 761.805544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.733958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:27.971249Z","time spent":"762.647901ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:28.734126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.596117ms","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":"warn","ts":"2026-07-14T17:01:28.734055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.811402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-14T17:01:28.734207Z","caller":"traceutil/trace.go:171","msg":"trace[828987986] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4919; }","duration":"542.662869ms","start":"2026-07-14T17:01:28.19149Z","end":"2026-07-14T17:01:28.734153Z","steps":["trace[828987986] 'agreement among raft nodes before linearized reading' (duration: 542.548817ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:28.734226Z","caller":"traceutil/trace.go:171","msg":"trace[1114132771] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4919; }","duration":"534.004626ms","start":"2026-07-14T17:01:28.200212Z","end":"2026-07-14T17:01:28.734217Z","steps":["trace[1114132771] 'agreement among raft nodes before linearized reading' (duration: 533.788502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.734253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:28.200201Z","time spent":"534.042366ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:01:28.734232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.736741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-14T17:01:28.734254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:28.191471Z","time spent":"542.77271ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-14T17:01:28.734297Z","caller":"traceutil/trace.go:171","msg":"trace[1273290415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4919; }","duration":"216.821712ms","start":"2026-07-14T17:01:28.517458Z","end":"2026-07-14T17:01:28.734279Z","steps":["trace[1273290415] 'agreement among raft nodes before linearized reading' (duration: 216.211411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.73446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.438793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:01:28.734862Z","caller":"traceutil/trace.go:171","msg":"trace[745929802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4919; }","duration":"218.840711ms","start":"2026-07-14T17:01:28.516002Z","end":"2026-07-14T17:01:28.734842Z","steps":["trace[745929802] 'agreement among raft nodes before linearized reading' (duration: 217.573277ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:28.963109Z","caller":"traceutil/trace.go:171","msg":"trace[49690726] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"223.771344ms","start":"2026-07-14T17:01:28.739304Z","end":"2026-07-14T17:01:28.963076Z","steps":["trace[49690726] 'process raft request' (duration: 162.944738ms)","trace[49690726] 'compare' (duration: 60.636312ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:28.963526Z","caller":"traceutil/trace.go:171","msg":"trace[1626473014] linearizableReadLoop","detail":"{readStateIndex:5111; appliedIndex:5104; }","duration":"215.07072ms","start":"2026-07-14T17:01:28.748434Z","end":"2026-07-14T17:01:28.963505Z","steps":["trace[1626473014] 'read index received' (duration: 24.070703ms)","trace[1626473014] 'applied index is now lower than readState.Index' (duration: 190.999017ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:28.963563Z","caller":"traceutil/trace.go:171","msg":"trace[926401635] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"223.03867ms","start":"2026-07-14T17:01:28.740492Z","end":"2026-07-14T17:01:28.963531Z","steps":["trace[926401635] 'process raft request' (duration: 222.728394ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:28.963653Z","caller":"traceutil/trace.go:171","msg":"trace[1942211312] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"222.995949ms","start":"2026-07-14T17:01:28.740646Z","end":"2026-07-14T17:01:28.963642Z","steps":["trace[1942211312] 'process raft request' (duration: 222.733314ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:28.963907Z","caller":"traceutil/trace.go:171","msg":"trace[1452286316] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"222.215204ms","start":"2026-07-14T17:01:28.741681Z","end":"2026-07-14T17:01:28.963896Z","steps":["trace[1452286316] 'process raft request' (duration: 221.742605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.96392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.517201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-14T17:01:28.963878Z","caller":"traceutil/trace.go:171","msg":"trace[1227091793] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"224.11339ms","start":"2026-07-14T17:01:28.739707Z","end":"2026-07-14T17:01:28.96382Z","steps":["trace[1227091793] 'process raft request' (duration: 223.328216ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:28.963985Z","caller":"traceutil/trace.go:171","msg":"trace[438569897] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"217.455275ms","start":"2026-07-14T17:01:28.746523Z","end":"2026-07-14T17:01:28.963978Z","steps":["trace[438569897] 'process raft request' (duration: 216.933236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.964041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.599092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-14T17:01:28.96407Z","caller":"traceutil/trace.go:171","msg":"trace[1245764293] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4926; }","duration":"101.657824ms","start":"2026-07-14T17:01:28.862403Z","end":"2026-07-14T17:01:28.964061Z","steps":["trace[1245764293] 'agreement among raft nodes before linearized reading' (duration: 101.577942ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:28.963962Z","caller":"traceutil/trace.go:171","msg":"trace[769222325] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4926; }","duration":"170.611983ms","start":"2026-07-14T17:01:28.793336Z","end":"2026-07-14T17:01:28.963948Z","steps":["trace[769222325] 'agreement among raft nodes before linearized reading' (duration: 170.43859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:28.964018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.550878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-07-14T17:01:28.964152Z","caller":"traceutil/trace.go:171","msg":"trace[455816326] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4926; }","duration":"215.717352ms","start":"2026-07-14T17:01:28.748408Z","end":"2026-07-14T17:01:28.964126Z","steps":["trace[455816326] 'agreement among raft nodes before linearized reading' (duration: 215.356075ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:29.136301Z","caller":"traceutil/trace.go:171","msg":"trace[878581093] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"161.291716ms","start":"2026-07-14T17:01:28.974981Z","end":"2026-07-14T17:01:29.136272Z","steps":["trace[878581093] 'process raft request' (duration: 161.027501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:29.463039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.01627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:01:29.463555Z","caller":"traceutil/trace.go:171","msg":"trace[2045172944] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"318.69309ms","start":"2026-07-14T17:01:29.144838Z","end":"2026-07-14T17:01:29.463531Z","steps":["trace[2045172944] 'process raft request' (duration: 173.088629ms)","trace[2045172944] 'compare' (duration: 144.865198ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:29.463547Z","caller":"traceutil/trace.go:171","msg":"trace[780111312] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"318.469555ms","start":"2026-07-14T17:01:29.145048Z","end":"2026-07-14T17:01:29.463517Z","steps":["trace[780111312] 'process raft request' (duration: 318.113468ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:29.463643Z","caller":"traceutil/trace.go:171","msg":"trace[250952184] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"309.738381ms","start":"2026-07-14T17:01:29.153885Z","end":"2026-07-14T17:01:29.463623Z","steps":["trace[250952184] 'process raft request' (duration: 309.402115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:29.463711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:29.145024Z","time spent":"318.601617ms","remote":"127.0.0.1:45330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:29.463862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:29.144816Z","time spent":"318.791652ms","remote":"127.0.0.1:45236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:01:29.46388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:29.153868Z","time spent":"309.835972ms","remote":"127.0.0.1:45236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:01:29.463863Z","caller":"traceutil/trace.go:171","msg":"trace[1609906188] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"317.6453ms","start":"2026-07-14T17:01:29.146195Z","end":"2026-07-14T17:01:29.46384Z","steps":["trace[1609906188] 'process raft request' (duration: 317.049119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:29.464016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:01:29.146176Z","time spent":"317.767052ms","remote":"127.0.0.1:45330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:05.412226Z","caller":"traceutil/trace.go:171","msg":"trace[1299703283] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"550.329869ms","start":"2026-07-14T17:02:04.861871Z","end":"2026-07-14T17:02:05.412201Z","steps":["trace[1299703283] 'process raft request' (duration: 550.182676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.412416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:04.861846Z","time spent":"550.460531ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:05.430974Z","caller":"traceutil/trace.go:171","msg":"trace[1589406810] linearizableReadLoop","detail":"{readStateIndex:5443; appliedIndex:5441; }","duration":"467.025789ms","start":"2026-07-14T17:02:04.963922Z","end":"2026-07-14T17:02:05.430947Z","steps":["trace[1589406810] 'read index received' (duration: 448.211447ms)","trace[1589406810] 'applied index is now lower than readState.Index' (duration: 18.813432ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:05.431039Z","caller":"traceutil/trace.go:171","msg":"trace[1709251327] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"569.112941ms","start":"2026-07-14T17:02:04.861901Z","end":"2026-07-14T17:02:05.431014Z","steps":["trace[1709251327] 'process raft request' (duration: 568.916326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.384181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:02:05.431174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.247143ms","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":"warn","ts":"2026-07-14T17:02:05.431208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:04.861874Z","time spent":"569.232293ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:05.431197Z","caller":"traceutil/trace.go:171","msg":"trace[1508032980] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5250; }","duration":"448.495553ms","start":"2026-07-14T17:02:04.98269Z","end":"2026-07-14T17:02:05.431186Z","steps":["trace[1508032980] 'agreement among raft nodes before linearized reading' (duration: 448.403451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:04.982658Z","time spent":"448.622416ms","remote":"127.0.0.1:45484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:02:05.431321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.070235ms","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-07-14T17:02:05.43136Z","caller":"traceutil/trace.go:171","msg":"trace[1509825560] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5250; }","duration":"331.152207ms","start":"2026-07-14T17:02:05.100196Z","end":"2026-07-14T17:02:05.431348Z","steps":["trace[1509825560] 'agreement among raft nodes before linearized reading' (duration: 331.024425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.541935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T17:02:05.431397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:05.100174Z","time spent":"331.215499ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6182,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-07-14T17:02:05.431235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.855415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-14T17:02:05.431431Z","caller":"traceutil/trace.go:171","msg":"trace[1818316646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5250; }","duration":"292.608766ms","start":"2026-07-14T17:02:05.138809Z","end":"2026-07-14T17:02:05.431418Z","steps":["trace[1818316646] 'agreement among raft nodes before linearized reading' (duration: 292.533525ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:05.431469Z","caller":"traceutil/trace.go:171","msg":"trace[1055398433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5250; }","duration":"297.1324ms","start":"2026-07-14T17:02:05.134327Z","end":"2026-07-14T17:02:05.431459Z","steps":["trace[1055398433] 'agreement among raft nodes before linearized reading' (duration: 296.809344ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:05.431239Z","caller":"traceutil/trace.go:171","msg":"trace[1388573342] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5250; }","duration":"467.354145ms","start":"2026-07-14T17:02:04.963869Z","end":"2026-07-14T17:02:05.431223Z","steps":["trace[1388573342] 'agreement among raft nodes before linearized reading' (duration: 467.171661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:04.963846Z","time spent":"467.669431ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:02:05.431584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.595908ms","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-07-14T17:02:05.431622Z","caller":"traceutil/trace.go:171","msg":"trace[1363966728] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5250; }","duration":"336.66864ms","start":"2026-07-14T17:02:05.094943Z","end":"2026-07-14T17:02:05.431612Z","steps":["trace[1363966728] 'agreement among raft nodes before linearized reading' (duration: 336.617269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:05.094927Z","time spent":"336.722151ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-07-14T17:02:05.431641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.849994ms","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-07-14T17:02:05.431693Z","caller":"traceutil/trace.go:171","msg":"trace[177595895] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5250; }","duration":"336.954276ms","start":"2026-07-14T17:02:05.094729Z","end":"2026-07-14T17:02:05.431683Z","steps":["trace[177595895] 'agreement among raft nodes before linearized reading' (duration: 336.880045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.892401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:02:05.431748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:05.094692Z","time spent":"337.028567ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"info","ts":"2026-07-14T17:02:05.43178Z","caller":"traceutil/trace.go:171","msg":"trace[1300521859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5250; }","duration":"370.979873ms","start":"2026-07-14T17:02:05.060785Z","end":"2026-07-14T17:02:05.431765Z","steps":["trace[1300521859] 'agreement among raft nodes before linearized reading' (duration: 370.926842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.58568ms","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":"warn","ts":"2026-07-14T17:02:05.431818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:05.060759Z","time spent":"371.052354ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:02:05.43185Z","caller":"traceutil/trace.go:171","msg":"trace[1985004310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5250; }","duration":"219.145591ms","start":"2026-07-14T17:02:05.212694Z","end":"2026-07-14T17:02:05.43184Z","steps":["trace[1985004310] 'agreement among raft nodes before linearized reading' (duration: 218.574741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.431905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.387443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T17:02:05.431679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.866805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:02:05.432011Z","caller":"traceutil/trace.go:171","msg":"trace[1529810655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5250; }","duration":"213.663958ms","start":"2026-07-14T17:02:05.218326Z","end":"2026-07-14T17:02:05.43199Z","steps":["trace[1529810655] 'agreement among raft nodes before linearized reading' (duration: 212.849443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.43239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.984307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:02:05.43244Z","caller":"traceutil/trace.go:171","msg":"trace[1612527413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5250; }","duration":"212.054298ms","start":"2026-07-14T17:02:05.220377Z","end":"2026-07-14T17:02:05.432431Z","steps":["trace[1612527413] 'agreement among raft nodes before linearized reading' (duration: 211.920695ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:05.432049Z","caller":"traceutil/trace.go:171","msg":"trace[1580753469] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:5250; }","duration":"414.288193ms","start":"2026-07-14T17:02:05.017748Z","end":"2026-07-14T17:02:05.432036Z","steps":["trace[1580753469] 'agreement among raft nodes before linearized reading' (duration: 413.852975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:05.433223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:05.01773Z","time spent":"415.477174ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:02:05.62227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.332127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:02:05.622381Z","caller":"traceutil/trace.go:171","msg":"trace[84295337] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"184.450003ms","start":"2026-07-14T17:02:05.43791Z","end":"2026-07-14T17:02:05.62236Z","steps":["trace[84295337] 'process raft request' (duration: 12.858732ms)","trace[84295337] 'compare' (duration: 171.212864ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:05.628753Z","caller":"traceutil/trace.go:171","msg":"trace[445760332] transaction","detail":"{read_only:false; response_revision:5252; number_of_response:1; }","duration":"190.368853ms","start":"2026-07-14T17:02:05.438372Z","end":"2026-07-14T17:02:05.628741Z","steps":["trace[445760332] 'process raft request' (duration: 190.176139ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:05.628767Z","caller":"traceutil/trace.go:171","msg":"trace[1724614044] transaction","detail":"{read_only:false; response_revision:5253; number_of_response:1; }","duration":"190.304932ms","start":"2026-07-14T17:02:05.438437Z","end":"2026-07-14T17:02:05.628742Z","steps":["trace[1724614044] 'process raft request' (duration: 190.22838ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:05.628972Z","caller":"traceutil/trace.go:171","msg":"trace[1811486166] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"190.515056ms","start":"2026-07-14T17:02:05.438439Z","end":"2026-07-14T17:02:05.628954Z","steps":["trace[1811486166] 'process raft request' (duration: 190.269311ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:05.629095Z","caller":"traceutil/trace.go:171","msg":"trace[696331467] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"189.087698ms","start":"2026-07-14T17:02:05.439996Z","end":"2026-07-14T17:02:05.629084Z","steps":["trace[696331467] 'process raft request' (duration: 189.029147ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:05.629107Z","caller":"traceutil/trace.go:171","msg":"trace[747018783] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"190.501735ms","start":"2026-07-14T17:02:05.438593Z","end":"2026-07-14T17:02:05.629095Z","steps":["trace[747018783] 'process raft request' (duration: 190.302782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:06.033863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.840652ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:02:06.033988Z","caller":"traceutil/trace.go:171","msg":"trace[1070659551] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"206.78447ms","start":"2026-07-14T17:02:05.827185Z","end":"2026-07-14T17:02:06.03397Z","steps":["trace[1070659551] 'process raft request' (duration: 23.743454ms)","trace[1070659551] 'compare' (duration: 182.683089ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:09.743079Z","caller":"traceutil/trace.go:171","msg":"trace[746616041] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5463; }","duration":"237.804078ms","start":"2026-07-14T17:02:09.505241Z","end":"2026-07-14T17:02:09.743045Z","steps":["trace[746616041] 'read index received' (duration: 237.582694ms)","trace[746616041] 'applied index is now lower than readState.Index' (duration: 219.664µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:09.743139Z","caller":"traceutil/trace.go:171","msg":"trace[1037291714] transaction","detail":"{read_only:false; response_revision:5270; number_of_response:1; }","duration":"297.591405ms","start":"2026-07-14T17:02:09.445518Z","end":"2026-07-14T17:02:09.743109Z","steps":["trace[1037291714] 'process raft request' (duration: 297.31251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:09.743359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.393568ms","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-07-14T17:02:09.74341Z","caller":"traceutil/trace.go:171","msg":"trace[7364309] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5270; }","duration":"300.50748ms","start":"2026-07-14T17:02:09.442891Z","end":"2026-07-14T17:02:09.743399Z","steps":["trace[7364309] 'agreement among raft nodes before linearized reading' (duration: 300.339407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:09.743441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:09.442863Z","time spent":"300.569881ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-07-14T17:02:09.743508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.292351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:02:09.743598Z","caller":"traceutil/trace.go:171","msg":"trace[550252316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5270; }","duration":"104.430622ms","start":"2026-07-14T17:02:09.639144Z","end":"2026-07-14T17:02:09.743575Z","steps":["trace[550252316] 'agreement among raft nodes before linearized reading' (duration: 104.130707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:09.743808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.54687ms","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-07-14T17:02:09.74385Z","caller":"traceutil/trace.go:171","msg":"trace[1846393830] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5270; }","duration":"300.615502ms","start":"2026-07-14T17:02:09.443222Z","end":"2026-07-14T17:02:09.743838Z","steps":["trace[1846393830] 'agreement among raft nodes before linearized reading' (duration: 299.91764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:09.743882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:09.443189Z","time spent":"300.685713ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-07-14T17:02:09.743917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.846734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-14T17:02:09.744157Z","caller":"traceutil/trace.go:171","msg":"trace[2063553180] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5270; }","duration":"102.1489ms","start":"2026-07-14T17:02:09.64195Z","end":"2026-07-14T17:02:09.744099Z","steps":["trace[2063553180] 'agreement among raft nodes before linearized reading' (duration: 101.636491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:09.958944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.316447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:02:09.959063Z","caller":"traceutil/trace.go:171","msg":"trace[163744806] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"210.09978ms","start":"2026-07-14T17:02:09.748942Z","end":"2026-07-14T17:02:09.959042Z","steps":["trace[163744806] 'process raft request' (duration: 109.58994ms)","trace[163744806] 'compare' (duration: 99.911719ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:02:09.961267Z","caller":"traceutil/trace.go:171","msg":"trace[1987494050] transaction","detail":"{read_only:false; response_revision:5272; number_of_response:1; }","duration":"212.25944ms","start":"2026-07-14T17:02:09.748975Z","end":"2026-07-14T17:02:09.961234Z","steps":["trace[1987494050] 'process raft request' (duration: 211.917523ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:09.961636Z","caller":"traceutil/trace.go:171","msg":"trace[2048442138] transaction","detail":"{read_only:false; response_revision:5273; number_of_response:1; }","duration":"211.972714ms","start":"2026-07-14T17:02:09.749631Z","end":"2026-07-14T17:02:09.961603Z","steps":["trace[2048442138] 'process raft request' (duration: 211.517066ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:09.961661Z","caller":"traceutil/trace.go:171","msg":"trace[1108008897] transaction","detail":"{read_only:false; response_revision:5275; number_of_response:1; }","duration":"210.296333ms","start":"2026-07-14T17:02:09.751336Z","end":"2026-07-14T17:02:09.961632Z","steps":["trace[1108008897] 'process raft request' (duration: 210.221581ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:09.961716Z","caller":"traceutil/trace.go:171","msg":"trace[1191837368] transaction","detail":"{read_only:false; response_revision:5274; number_of_response:1; }","duration":"211.638608ms","start":"2026-07-14T17:02:09.750058Z","end":"2026-07-14T17:02:09.961696Z","steps":["trace[1191837368] 'process raft request' (duration: 211.372343ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:09.961753Z","caller":"traceutil/trace.go:171","msg":"trace[210506765] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"210.367874ms","start":"2026-07-14T17:02:09.751346Z","end":"2026-07-14T17:02:09.961714Z","steps":["trace[210506765] 'process raft request' (duration: 210.247712ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:15.930098Z","caller":"traceutil/trace.go:171","msg":"trace[61200347] transaction","detail":"{read_only:false; response_revision:5306; number_of_response:1; }","duration":"398.499746ms","start":"2026-07-14T17:02:15.53157Z","end":"2026-07-14T17:02:15.930069Z","steps":["trace[61200347] 'process raft request' (duration: 398.365313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:15.930502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.531558Z","time spent":"398.68211ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:16.374887Z","caller":"traceutil/trace.go:171","msg":"trace[1035895406] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"609.59566ms","start":"2026-07-14T17:02:15.76527Z","end":"2026-07-14T17:02:16.374866Z","steps":["trace[1035895406] 'process raft request' (duration: 609.463398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.765242Z","time spent":"609.701971ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:16.375008Z","caller":"traceutil/trace.go:171","msg":"trace[1737426792] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"150.632334ms","start":"2026-07-14T17:02:16.224355Z","end":"2026-07-14T17:02:16.374987Z","steps":["trace[1737426792] 'process raft request' (duration: 150.454391ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:16.374979Z","caller":"traceutil/trace.go:171","msg":"trace[242621244] linearizableReadLoop","detail":"{readStateIndex:5502; appliedIndex:5501; }","duration":"615.968429ms","start":"2026-07-14T17:02:15.758975Z","end":"2026-07-14T17:02:16.374944Z","steps":["trace[242621244] 'read index received' (duration: 171.26744ms)","trace[242621244] 'applied index is now lower than readState.Index' (duration: 444.696809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:16.375085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.094552ms","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-07-14T17:02:16.375138Z","caller":"traceutil/trace.go:171","msg":"trace[39675651] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5310; }","duration":"616.195034ms","start":"2026-07-14T17:02:15.75893Z","end":"2026-07-14T17:02:16.375125Z","steps":["trace[39675651] 'agreement among raft nodes before linearized reading' (duration: 616.099342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.196914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:02:16.375183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.758908Z","time spent":"616.267176ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"info","ts":"2026-07-14T17:02:16.375221Z","caller":"traceutil/trace.go:171","msg":"trace[688428769] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5310; }","duration":"616.259835ms","start":"2026-07-14T17:02:15.758952Z","end":"2026-07-14T17:02:16.375212Z","steps":["trace[688428769] 'agreement among raft nodes before linearized reading' (duration: 616.198464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.758932Z","time spent":"616.309666ms","remote":"127.0.0.1:45290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-07-14T17:02:16.375262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.665953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-14T17:02:16.375316Z","caller":"traceutil/trace.go:171","msg":"trace[577408328] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5310; }","duration":"380.768625ms","start":"2026-07-14T17:02:15.994528Z","end":"2026-07-14T17:02:16.375296Z","steps":["trace[577408328] 'agreement among raft nodes before linearized reading' (duration: 380.624412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.937238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-14T17:02:16.375434Z","caller":"traceutil/trace.go:171","msg":"trace[796892325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5310; }","duration":"380.986728ms","start":"2026-07-14T17:02:15.994439Z","end":"2026-07-14T17:02:16.375426Z","steps":["trace[796892325] 'agreement among raft nodes before linearized reading' (duration: 380.873006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.994431Z","time spent":"381.02893ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:02:16.375502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.994498Z","time spent":"380.918817ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:02:16.375588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:02:16.375616Z","caller":"traceutil/trace.go:171","msg":"trace[542813578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5310; }","duration":"384.368412ms","start":"2026-07-14T17:02:15.991239Z","end":"2026-07-14T17:02:16.375607Z","steps":["trace[542813578] 'agreement among raft nodes before linearized reading' (duration: 384.2901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.991231Z","time spent":"384.415712ms","remote":"127.0.0.1:45320","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":"warn","ts":"2026-07-14T17:02:16.375739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.84848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-14T17:02:16.375777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.964859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T17:02:16.375787Z","caller":"traceutil/trace.go:171","msg":"trace[1132973768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5310; }","duration":"384.935152ms","start":"2026-07-14T17:02:15.990839Z","end":"2026-07-14T17:02:16.375774Z","steps":["trace[1132973768] 'agreement among raft nodes before linearized reading' (duration: 384.714728ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:16.375807Z","caller":"traceutil/trace.go:171","msg":"trace[1436394184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5310; }","duration":"381.011179ms","start":"2026-07-14T17:02:15.994787Z","end":"2026-07-14T17:02:16.375799Z","steps":["trace[1436394184] 'agreement among raft nodes before linearized reading' (duration: 380.931038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.990817Z","time spent":"385.001274ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:02:16.375846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.99478Z","time spent":"381.05609ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:02:16.375782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.538975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-14T17:02:16.375911Z","caller":"traceutil/trace.go:171","msg":"trace[50450984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5310; }","duration":"376.686909ms","start":"2026-07-14T17:02:15.999216Z","end":"2026-07-14T17:02:16.375903Z","steps":["trace[50450984] 'agreement among raft nodes before linearized reading' (duration: 376.483364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.375945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.999209Z","time spent":"376.72964ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:02:16.376094Z","caller":"traceutil/trace.go:171","msg":"trace[1710552553] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"800.332555ms","start":"2026-07-14T17:02:15.574583Z","end":"2026-07-14T17:02:16.374916Z","steps":["trace[1710552553] 'process raft request' (duration: 793.352795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:16.376804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:15.574559Z","time spent":"801.984707ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:30.600099Z","caller":"traceutil/trace.go:171","msg":"trace[1526806002] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"124.215606ms","start":"2026-07-14T17:02:30.475866Z","end":"2026-07-14T17:02:30.600081Z","steps":["trace[1526806002] 'process raft request' (duration: 124.059193ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:30.624034Z","caller":"traceutil/trace.go:171","msg":"trace[529608502] linearizableReadLoop","detail":"{readStateIndex:5605; appliedIndex:5604; }","duration":"127.075321ms","start":"2026-07-14T17:02:30.496941Z","end":"2026-07-14T17:02:30.624016Z","steps":["trace[529608502] 'read index received' (duration: 103.216106ms)","trace[529608502] 'applied index is now lower than readState.Index' (duration: 23.858505ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:30.624229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.275255ms","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":"warn","ts":"2026-07-14T17:02:30.624248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.684793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-14T17:02:30.624282Z","caller":"traceutil/trace.go:171","msg":"trace[1495622698] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5406; }","duration":"127.370686ms","start":"2026-07-14T17:02:30.496899Z","end":"2026-07-14T17:02:30.624269Z","steps":["trace[1495622698] 'agreement among raft nodes before linearized reading' (duration: 127.236263ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:30.624289Z","caller":"traceutil/trace.go:171","msg":"trace[1255807040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5406; }","duration":"104.757624ms","start":"2026-07-14T17:02:30.519521Z","end":"2026-07-14T17:02:30.624279Z","steps":["trace[1255807040] 'agreement among raft nodes before linearized reading' (duration: 104.619821ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:30.624464Z","caller":"traceutil/trace.go:171","msg":"trace[1346642068] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"138.875551ms","start":"2026-07-14T17:02:30.485576Z","end":"2026-07-14T17:02:30.624451Z","steps":["trace[1346642068] 'process raft request' (duration: 125.241547ms)","trace[1346642068] 'compare' (duration: 13.112145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:30.624571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.664743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-14T17:02:30.624598Z","caller":"traceutil/trace.go:171","msg":"trace[35027685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5406; }","duration":"103.708244ms","start":"2026-07-14T17:02:30.520883Z","end":"2026-07-14T17:02:30.624591Z","steps":["trace[35027685] 'agreement among raft nodes before linearized reading' (duration: 103.636282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:30.624723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.57863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:02:30.624748Z","caller":"traceutil/trace.go:171","msg":"trace[601675613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5406; }","duration":"104.62168ms","start":"2026-07-14T17:02:30.520119Z","end":"2026-07-14T17:02:30.624741Z","steps":["trace[601675613] 'agreement among raft nodes before linearized reading' (duration: 104.564459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:30.624803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.215533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:02:30.624833Z","caller":"traceutil/trace.go:171","msg":"trace[2110758280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5406; }","duration":"105.262204ms","start":"2026-07-14T17:02:30.519564Z","end":"2026-07-14T17:02:30.624826Z","steps":["trace[2110758280] 'agreement among raft nodes before linearized reading' (duration: 105.186603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:31.042819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.511335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:02:31.04292Z","caller":"traceutil/trace.go:171","msg":"trace[1369768564] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5412; }","duration":"233.640957ms","start":"2026-07-14T17:02:30.80924Z","end":"2026-07-14T17:02:31.042881Z","steps":["trace[1369768564] 'count revisions from in-memory index tree' (duration: 233.397843ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:32.170118Z","caller":"traceutil/trace.go:171","msg":"trace[1074486125] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"151.794092ms","start":"2026-07-14T17:02:32.018291Z","end":"2026-07-14T17:02:32.170085Z","steps":["trace[1074486125] 'process raft request' (duration: 151.537116ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:40.757411Z","caller":"traceutil/trace.go:171","msg":"trace[1378823272] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"122.474762ms","start":"2026-07-14T17:02:40.634867Z","end":"2026-07-14T17:02:40.757341Z","steps":["trace[1378823272] 'process raft request' (duration: 122.268038ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:40.763161Z","caller":"traceutil/trace.go:171","msg":"trace[329476231] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"105.647629ms","start":"2026-07-14T17:02:40.657503Z","end":"2026-07-14T17:02:40.76315Z","steps":["trace[329476231] 'process raft request' (duration: 105.477696ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:41.070075Z","caller":"traceutil/trace.go:171","msg":"trace[394112536] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"301.355335ms","start":"2026-07-14T17:02:40.768701Z","end":"2026-07-14T17:02:41.070056Z","steps":["trace[394112536] 'process raft request' (duration: 296.913672ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:41.070129Z","caller":"traceutil/trace.go:171","msg":"trace[895263620] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"301.367525ms","start":"2026-07-14T17:02:40.768738Z","end":"2026-07-14T17:02:41.070106Z","steps":["trace[895263620] 'process raft request' (duration: 301.172371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.070278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.768734Z","time spent":"301.474396ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:41.070471Z","caller":"traceutil/trace.go:171","msg":"trace[1271616111] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"301.852184ms","start":"2026-07-14T17:02:40.768603Z","end":"2026-07-14T17:02:41.070455Z","steps":["trace[1271616111] 'process raft request' (duration: 301.417626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.070539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.768594Z","time spent":"301.911305ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:41.070582Z","caller":"traceutil/trace.go:171","msg":"trace[965759008] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"301.042419ms","start":"2026-07-14T17:02:40.769528Z","end":"2026-07-14T17:02:41.070571Z","steps":["trace[965759008] 'process raft request' (duration: 300.52643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:02:41.070649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.769521Z","time spent":"301.09619ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:02:41.070636Z","caller":"traceutil/trace.go:171","msg":"trace[1923444070] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"299.720674ms","start":"2026-07-14T17:02:40.7709Z","end":"2026-07-14T17:02:41.070621Z","steps":["trace[1923444070] 'process raft request' (duration: 299.187334ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:41.070522Z","caller":"traceutil/trace.go:171","msg":"trace[1742544319] linearizableReadLoop","detail":"{readStateIndex:5692; appliedIndex:5687; }","duration":"217.280278ms","start":"2026-07-14T17:02:40.853228Z","end":"2026-07-14T17:02:41.070509Z","steps":["trace[1742544319] 'read index received' (duration: 212.394877ms)","trace[1742544319] 'applied index is now lower than readState.Index' (duration: 4.884411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:02:41.071269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:02:40.768695Z","time spent":"301.432876ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:02:41.070837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.612055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:02:41.071542Z","caller":"traceutil/trace.go:171","msg":"trace[1006854658] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:5492; }","duration":"218.342067ms","start":"2026-07-14T17:02:40.853174Z","end":"2026-07-14T17:02:41.071516Z","steps":["trace[1006854658] 'agreement among raft nodes before linearized reading' (duration: 217.623574ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:02:49.117659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-07-14T17:02:49.202844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"83.590186ms","hash":3823490120,"current-db-size-bytes":53280768,"current-db-size":"53 MB","current-db-size-in-use-bytes":29192192,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-14T17:02:49.202896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3823490120,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-07-14T17:05:21.622596Z","caller":"traceutil/trace.go:171","msg":"trace[1252899848] transaction","detail":"{read_only:false; response_revision:6636; number_of_response:1; }","duration":"277.12715ms","start":"2026-07-14T17:05:21.345449Z","end":"2026-07-14T17:05:21.622576Z","steps":["trace[1252899848] 'process raft request' (duration: 277.018165ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:21.623872Z","caller":"traceutil/trace.go:171","msg":"trace[684424379] transaction","detail":"{read_only:false; response_revision:6637; number_of_response:1; }","duration":"223.214517ms","start":"2026-07-14T17:05:21.400647Z","end":"2026-07-14T17:05:21.623862Z","steps":["trace[684424379] 'process raft request' (duration: 222.951689ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:22.112144Z","caller":"traceutil/trace.go:171","msg":"trace[564852268] transaction","detail":"{read_only:false; response_revision:6639; number_of_response:1; }","duration":"133.248428ms","start":"2026-07-14T17:05:21.978879Z","end":"2026-07-14T17:05:22.112128Z","steps":["trace[564852268] 'process raft request' (duration: 132.897988ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:22.233732Z","caller":"traceutil/trace.go:171","msg":"trace[2059745932] transaction","detail":"{read_only:false; response_revision:6640; number_of_response:1; }","duration":"115.846332ms","start":"2026-07-14T17:05:22.117862Z","end":"2026-07-14T17:05:22.233709Z","steps":["trace[2059745932] 'process raft request' (duration: 115.665907ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:22.233983Z","caller":"traceutil/trace.go:171","msg":"trace[1859517021] transaction","detail":"{read_only:false; response_revision:6641; number_of_response:1; }","duration":"115.980835ms","start":"2026-07-14T17:05:22.11799Z","end":"2026-07-14T17:05:22.233971Z","steps":["trace[1859517021] 'process raft request' (duration: 115.679557ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:22.234269Z","caller":"traceutil/trace.go:171","msg":"trace[641008239] transaction","detail":"{read_only:false; response_revision:6643; number_of_response:1; }","duration":"112.207427ms","start":"2026-07-14T17:05:22.122042Z","end":"2026-07-14T17:05:22.234249Z","steps":["trace[641008239] 'process raft request' (duration: 112.152926ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:05:22.234448Z","caller":"traceutil/trace.go:171","msg":"trace[48260463] transaction","detail":"{read_only:false; response_revision:6642; number_of_response:1; }","duration":"113.238936ms","start":"2026-07-14T17:05:22.121196Z","end":"2026-07-14T17:05:22.234435Z","steps":["trace[48260463] 'process raft request' (duration: 112.610109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:06:03.578059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.539399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/horizon-78d7b74bbf-kn6nl.18c236e367ffaa52\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-07-14T17:06:03.578115Z","caller":"traceutil/trace.go:171","msg":"trace[1319476548] range","detail":"{range_begin:/registry/events/openstack/horizon-78d7b74bbf-kn6nl.18c236e367ffaa52; range_end:; response_count:1; response_revision:6857; }","duration":"848.637132ms","start":"2026-07-14T17:06:02.729465Z","end":"2026-07-14T17:06:03.578102Z","steps":["trace[1319476548] 'range keys from in-memory index tree' (duration: 848.421176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:06:03.578143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:06:02.729428Z","time spent":"848.708624ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":878,"request content":"key:\"/registry/events/openstack/horizon-78d7b74bbf-kn6nl.18c236e367ffaa52\" "} {"level":"info","ts":"2026-07-14T17:06:03.592668Z","caller":"traceutil/trace.go:171","msg":"trace[480504520] transaction","detail":"{read_only:false; response_revision:6858; number_of_response:1; }","duration":"574.49237ms","start":"2026-07-14T17:06:03.018127Z","end":"2026-07-14T17:06:03.592619Z","steps":["trace[480504520] 'process raft request' (duration: 574.358386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:06:03.592808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:06:03.018096Z","time spent":"574.644704ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:07:43.155722Z","caller":"traceutil/trace.go:171","msg":"trace[2074881756] transaction","detail":"{read_only:false; response_revision:7380; number_of_response:1; }","duration":"246.928231ms","start":"2026-07-14T17:07:42.908763Z","end":"2026-07-14T17:07:43.155691Z","steps":["trace[2074881756] 'process raft request' (duration: 246.759647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:43.591324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.110532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-14T17:07:43.591327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.6002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-14T17:07:43.591405Z","caller":"traceutil/trace.go:171","msg":"trace[1357425901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7380; }","duration":"322.221585ms","start":"2026-07-14T17:07:43.269166Z","end":"2026-07-14T17:07:43.591388Z","steps":["trace[1357425901] 'range keys from in-memory index tree' (duration: 321.966389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:43.591441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:43.269158Z","time spent":"322.276726ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T17:07:43.591443Z","caller":"traceutil/trace.go:171","msg":"trace[2006108745] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7380; }","duration":"321.776404ms","start":"2026-07-14T17:07:43.269636Z","end":"2026-07-14T17:07:43.591413Z","steps":["trace[2006108745] 'range keys from in-memory index tree' (duration: 321.430265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:43.591477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.236405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T17:07:43.591518Z","caller":"traceutil/trace.go:171","msg":"trace[1691098156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7380; }","duration":"322.287427ms","start":"2026-07-14T17:07:43.269221Z","end":"2026-07-14T17:07:43.591509Z","steps":["trace[1691098156] 'range keys from in-memory index tree' (duration: 322.174614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:43.591512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:43.269617Z","time spent":"321.880487ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T17:07:43.591544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:43.269216Z","time spent":"322.322788ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:07:43.591648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.500992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T17:07:43.591694Z","caller":"traceutil/trace.go:171","msg":"trace[74682911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7380; }","duration":"322.570413ms","start":"2026-07-14T17:07:43.269109Z","end":"2026-07-14T17:07:43.59168Z","steps":["trace[74682911] 'range keys from in-memory index tree' (duration: 322.42493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:43.59173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:43.268905Z","time spent":"322.81527ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:07:43.592003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.736924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:07:43.592034Z","caller":"traceutil/trace.go:171","msg":"trace[1606134910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7380; }","duration":"315.789205ms","start":"2026-07-14T17:07:43.276235Z","end":"2026-07-14T17:07:43.592024Z","steps":["trace[1606134910] 'range keys from in-memory index tree' (duration: 315.677833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:43.592053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:43.276219Z","time spent":"315.829566ms","remote":"127.0.0.1:45052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:07:43.592129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.985683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-14T17:07:43.592226Z","caller":"traceutil/trace.go:171","msg":"trace[2128945838] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7380; }","duration":"323.319803ms","start":"2026-07-14T17:07:43.268887Z","end":"2026-07-14T17:07:43.592207Z","steps":["trace[2128945838] 'range keys from in-memory index tree' (duration: 322.778959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:07:43.592275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:07:43.268871Z","time spent":"323.381334ms","remote":"127.0.0.1:45256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-14T17:07:49.125092Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5559} {"level":"info","ts":"2026-07-14T17:07:49.204895Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5559,"took":"78.232269ms","hash":1393482902,"current-db-size-bytes":53280768,"current-db-size":"53 MB","current-db-size-in-use-bytes":18276352,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-14T17:07:49.205005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1393482902,"revision":5559,"compact-revision":2896}