{"level":"warn","ts":"2026-09-08T15:47:52.831745Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:47:52.831849Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.4: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://162.253.55.4:2380","--initial-cluster=instance=https://162.253.55.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.4: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-09-08T15:47:52.831921Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:47:52.831932Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-09-08T15:47:52.831959Z","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-09-08T15:47:52.832861Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"]} {"level":"info","ts":"2026-09-08T15:47:52.832977Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.4: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-09-08T15:47:52.840213Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.946481ms"} {"level":"info","ts":"2026-09-08T15:47:52.850637Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"caafea9f270da559","cluster-id":"271ff399bb4addea"} {"level":"info","ts":"2026-09-08T15:47:52.850851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:47:52.850939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 0"} {"level":"info","ts":"2026-09-08T15:47:52.850978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft caafea9f270da559 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:47:52.850996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became follower at term 1"} {"level":"info","ts":"2026-09-08T15:47:52.851077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"warn","ts":"2026-09-08T15:47:52.859995Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:47:52.862084Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:47:52.86386Z","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-09-08T15:47:52.866621Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"caafea9f270da559","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:47:52.866954Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"caafea9f270da559","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:47:52.867119Z","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-09-08T15:47:52.867456Z","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-09-08T15:47:52.867472Z","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-09-08T15:47:52.868633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 switched to configuration voters=(14605150085861713241)"} {"level":"info","ts":"2026-09-08T15:47:52.870135Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","added-peer-id":"caafea9f270da559","added-peer-peer-urls":["https://162.253.55.4:2380"]} {"level":"info","ts":"2026-09-08T15:47:52.873294Z","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-09-08T15:47:52.873465Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-09-08T15:47:52.873519Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.4:2380"} {"level":"info","ts":"2026-09-08T15:47:52.873874Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"caafea9f270da559","initial-advertise-peer-urls":["https://162.253.55.4:2380"],"listen-peer-urls":["https://162.253.55.4:2380"],"advertise-client-urls":["https://162.253.55.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:47:52.873926Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:47:53.251907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:47:53.251988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:47:53.252026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgPreVoteResp from caafea9f270da559 at term 1"} {"level":"info","ts":"2026-09-08T15:47:53.252046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:47:53.252057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 received MsgVoteResp from caafea9f270da559 at term 2"} {"level":"info","ts":"2026-09-08T15:47:53.252073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"caafea9f270da559 became leader at term 2"} {"level":"info","ts":"2026-09-08T15:47:53.252092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: caafea9f270da559 elected leader caafea9f270da559 at term 2"} {"level":"info","ts":"2026-09-08T15:47:53.253312Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:47:53.254491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:47:53.254479Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"caafea9f270da559","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.4:2379]}","request-path":"/0/members/caafea9f270da559/attributes","cluster-id":"271ff399bb4addea","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:47:53.254594Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:47:53.25491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:47:53.254971Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:47:53.255295Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"271ff399bb4addea","local-member-id":"caafea9f270da559","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:47:53.255598Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:47:53.255648Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:47:53.257572Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:47:53.25875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.4:2379"} {"level":"info","ts":"2026-09-08T15:48:13.331933Z","caller":"traceutil/trace.go:171","msg":"trace[1086449467] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"303.899502ms","start":"2026-09-08T15:48:13.027988Z","end":"2026-09-08T15:48:13.331888Z","steps":["trace[1086449467] 'read index received' (duration: 303.689517ms)","trace[1086449467] 'applied index is now lower than readState.Index' (duration: 208.425µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:13.332021Z","caller":"traceutil/trace.go:171","msg":"trace[369087167] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"317.376635ms","start":"2026-09-08T15:48:13.014593Z","end":"2026-09-08T15:48:13.331969Z","steps":["trace[369087167] 'process raft request' (duration: 317.052647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:13.332296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.407458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:48:13.332349Z","caller":"traceutil/trace.go:171","msg":"trace[831010573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"196.537841ms","start":"2026-09-08T15:48:13.135794Z","end":"2026-09-08T15:48:13.332332Z","steps":["trace[831010573] 'agreement among raft nodes before linearized reading' (duration: 196.413148ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:13.332195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.177128ms","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-09-08T15:48:13.332617Z","caller":"traceutil/trace.go:171","msg":"trace[113747000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:382; }","duration":"304.535606ms","start":"2026-09-08T15:48:13.027918Z","end":"2026-09-08T15:48:13.332453Z","steps":["trace[113747000] 'agreement among raft nodes before linearized reading' (duration: 304.174178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:13.332724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:13.027881Z","time spent":"304.826704ms","remote":"127.0.0.1:37398","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-09-08T15:48:13.333194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:13.01453Z","time spent":"317.808735ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:48:34.87358Z","caller":"traceutil/trace.go:171","msg":"trace[127331921] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"141.965593ms","start":"2026-09-08T15:48:34.73157Z","end":"2026-09-08T15:48:34.873536Z","steps":["trace[127331921] 'process raft request' (duration: 133.011526ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:34.875114Z","caller":"traceutil/trace.go:171","msg":"trace[2045467272] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"135.828042ms","start":"2026-09-08T15:48:34.739277Z","end":"2026-09-08T15:48:34.875105Z","steps":["trace[2045467272] 'process raft request' (duration: 135.665507ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:40.856586Z","caller":"traceutil/trace.go:171","msg":"trace[758238344] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"154.24568ms","start":"2026-09-08T15:48:40.702267Z","end":"2026-09-08T15:48:40.856513Z","steps":["trace[758238344] 'process raft request' (duration: 153.997694ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:41.382386Z","caller":"traceutil/trace.go:171","msg":"trace[1116111275] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"135.689539ms","start":"2026-09-08T15:48:41.246659Z","end":"2026-09-08T15:48:41.382348Z","steps":["trace[1116111275] 'process raft request' (duration: 108.540629ms)","trace[1116111275] 'compare' (duration: 26.940095ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:42.045244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.05055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T15:48:42.045381Z","caller":"traceutil/trace.go:171","msg":"trace[289498601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"508.275855ms","start":"2026-09-08T15:48:41.537082Z","end":"2026-09-08T15:48:42.045358Z","steps":["trace[289498601] 'range keys from in-memory index tree' (duration: 507.886365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:42.045436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:41.537053Z","time spent":"508.371937ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T15:48:42.045485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.508414ms","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-09-08T15:48:42.045644Z","caller":"traceutil/trace.go:171","msg":"trace[70413115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"450.694969ms","start":"2026-09-08T15:48:41.594915Z","end":"2026-09-08T15:48:42.04561Z","steps":["trace[70413115] 'range keys from in-memory index tree' (duration: 450.360881ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:42.04569Z","caller":"traceutil/trace.go:171","msg":"trace[1431017529] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"255.428588ms","start":"2026-09-08T15:48:41.790237Z","end":"2026-09-08T15:48:42.045666Z","steps":["trace[1431017529] 'process raft request' (duration: 248.522767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:42.045709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:41.594814Z","time spent":"450.879642ms","remote":"127.0.0.1:37398","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-09-08T15:48:57.499731Z","caller":"traceutil/trace.go:171","msg":"trace[536182005] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"221.170082ms","start":"2026-09-08T15:48:57.278512Z","end":"2026-09-08T15:48:57.499682Z","steps":["trace[536182005] 'process raft request' (duration: 220.914217ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:29.280477Z","caller":"traceutil/trace.go:171","msg":"trace[1526525151] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"137.965256ms","start":"2026-09-08T15:49:29.142474Z","end":"2026-09-08T15:49:29.280439Z","steps":["trace[1526525151] 'process raft request' (duration: 137.783612ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:50.786415Z","caller":"traceutil/trace.go:171","msg":"trace[621776145] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"173.157589ms","start":"2026-09-08T15:49:50.613217Z","end":"2026-09-08T15:49:50.786375Z","steps":["trace[621776145] 'process raft request' (duration: 172.952785ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:16.784305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.758085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:50:16.784435Z","caller":"traceutil/trace.go:171","msg":"trace[573116938] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"297.894082ms","start":"2026-09-08T15:50:16.486524Z","end":"2026-09-08T15:50:16.784418Z","steps":["trace[573116938] 'read index received' (duration: 69.611µs)","trace[573116938] 'applied index is now lower than readState.Index' (duration: 297.823111ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:16.784579Z","caller":"traceutil/trace.go:171","msg":"trace[1634278127] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"447.121955ms","start":"2026-09-08T15:50:16.337444Z","end":"2026-09-08T15:50:16.784566Z","steps":["trace[1634278127] 'process raft request' (duration: 118.862498ms)","trace[1634278127] 'compare' (duration: 327.51849ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:50:16.784654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:50:16.337394Z","time spent":"447.222517ms","remote":"127.0.0.1:37398","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-09-08T15:50:16.784862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.050849ms","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-09-08T15:50:16.784975Z","caller":"traceutil/trace.go:171","msg":"trace[782849730] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1835; }","duration":"282.214913ms","start":"2026-09-08T15:50:16.502729Z","end":"2026-09-08T15:50:16.784944Z","steps":["trace[782849730] 'agreement among raft nodes before linearized reading' (duration: 281.873695ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:16.785058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.235262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T15:50:16.785103Z","caller":"traceutil/trace.go:171","msg":"trace[1468331894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1835; }","duration":"177.333945ms","start":"2026-09-08T15:50:16.607759Z","end":"2026-09-08T15:50:16.785093Z","steps":["trace[1468331894] 'agreement among raft nodes before linearized reading' (duration: 177.179311ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:50:16.785148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.605208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9dd7223f-d1aa-49a3-a233-86f10aca6ee1\" ","response":"range_response_count:1 size:3625"} {"level":"warn","ts":"2026-09-08T15:50:16.785208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.811936ms","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-09-08T15:50:16.785254Z","caller":"traceutil/trace.go:171","msg":"trace[1706836041] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9dd7223f-d1aa-49a3-a233-86f10aca6ee1; range_end:; response_count:1; response_revision:1835; }","duration":"298.770062ms","start":"2026-09-08T15:50:16.486459Z","end":"2026-09-08T15:50:16.785229Z","steps":["trace[1706836041] 'agreement among raft nodes before linearized reading' (duration: 298.483076ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:16.785311Z","caller":"traceutil/trace.go:171","msg":"trace[1658870816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1835; }","duration":"177.99842ms","start":"2026-09-08T15:50:16.607284Z","end":"2026-09-08T15:50:16.785283Z","steps":["trace[1658870816] 'agreement among raft nodes before linearized reading' (duration: 177.673843ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:50:16.975671Z","caller":"traceutil/trace.go:171","msg":"trace[1468366327] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"184.022067ms","start":"2026-09-08T15:50:16.791615Z","end":"2026-09-08T15:50:16.975637Z","steps":["trace[1468366327] 'process raft request' (duration: 147.779738ms)","trace[1468366327] 'compare' (duration: 36.010554ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:50:16.975906Z","caller":"traceutil/trace.go:171","msg":"trace[842412505] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"181.596132ms","start":"2026-09-08T15:50:16.794295Z","end":"2026-09-08T15:50:16.975891Z","steps":["trace[842412505] 'process raft request' (duration: 181.275245ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:01.213741Z","caller":"traceutil/trace.go:171","msg":"trace[431793366] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"137.612868ms","start":"2026-09-08T15:51:01.076092Z","end":"2026-09-08T15:51:01.213705Z","steps":["trace[431793366] 'process raft request' (duration: 101.137654ms)","trace[431793366] 'compare' (duration: 36.262389ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:06.691272Z","caller":"traceutil/trace.go:171","msg":"trace[420551093] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"159.929887ms","start":"2026-09-08T15:51:06.531316Z","end":"2026-09-08T15:51:06.691246Z","steps":["trace[420551093] 'process raft request' (duration: 159.766023ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:06.766186Z","caller":"traceutil/trace.go:171","msg":"trace[332172775] linearizableReadLoop","detail":"{readStateIndex:2249; appliedIndex:2247; }","duration":"122.704515ms","start":"2026-09-08T15:51:06.643445Z","end":"2026-09-08T15:51:06.76615Z","steps":["trace[332172775] 'read index received' (duration: 47.749882ms)","trace[332172775] 'applied index is now lower than readState.Index' (duration: 74.952963ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:06.766356Z","caller":"traceutil/trace.go:171","msg":"trace[1671102207] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"220.925692ms","start":"2026-09-08T15:51:06.545369Z","end":"2026-09-08T15:51:06.766294Z","steps":["trace[1671102207] 'process raft request' (duration: 220.593134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:06.766624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.144616ms","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-09-08T15:51:06.76694Z","caller":"traceutil/trace.go:171","msg":"trace[517968675] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2164; }","duration":"123.494594ms","start":"2026-09-08T15:51:06.64339Z","end":"2026-09-08T15:51:06.766884Z","steps":["trace[517968675] 'agreement among raft nodes before linearized reading' (duration: 122.953602ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:07.261139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.174191ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:51:07.261281Z","caller":"traceutil/trace.go:171","msg":"trace[1735391526] linearizableReadLoop","detail":"{readStateIndex:2250; appliedIndex:2249; }","duration":"420.315502ms","start":"2026-09-08T15:51:06.840941Z","end":"2026-09-08T15:51:07.261257Z","steps":["trace[1735391526] 'read index received' (duration: 127.823673ms)","trace[1735391526] 'applied index is now lower than readState.Index' (duration: 292.471298ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:07.261637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.943314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:51:07.261716Z","caller":"traceutil/trace.go:171","msg":"trace[1822614862] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2165; }","duration":"347.083137ms","start":"2026-09-08T15:51:06.914607Z","end":"2026-09-08T15:51:07.26169Z","steps":["trace[1822614862] 'agreement among raft nodes before linearized reading' (duration: 346.936434ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:07.261771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:06.914583Z","time spent":"347.174299ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-09-08T15:51:07.261823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.879134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T15:51:07.261889Z","caller":"traceutil/trace.go:171","msg":"trace[1019886481] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2165; }","duration":"420.937975ms","start":"2026-09-08T15:51:06.840926Z","end":"2026-09-08T15:51:07.261864Z","steps":["trace[1019886481] 'agreement among raft nodes before linearized reading' (duration: 420.838393ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:07.261885Z","caller":"traceutil/trace.go:171","msg":"trace[1420203818] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"488.621013ms","start":"2026-09-08T15:51:06.773215Z","end":"2026-09-08T15:51:07.261836Z","steps":["trace[1420203818] 'process raft request' (duration: 195.622304ms)","trace[1420203818] 'compare' (duration: 292.046228ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:07.261939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:06.840849Z","time spent":"421.075698ms","remote":"127.0.0.1:37564","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-09-08T15:51:07.261949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.471722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:51:07.262008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:06.773193Z","time spent":"488.750837ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:51:07.261982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.331062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:51:07.262015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.063592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-09-08T15:51:07.26209Z","caller":"traceutil/trace.go:171","msg":"trace[263783737] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2165; }","duration":"124.496347ms","start":"2026-09-08T15:51:07.137563Z","end":"2026-09-08T15:51:07.262059Z","steps":["trace[263783737] 'agreement among raft nodes before linearized reading' (duration: 124.266961ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:07.262109Z","caller":"traceutil/trace.go:171","msg":"trace[620675859] range","detail":"{range_begin:/registry/masterleases/162.253.55.4; range_end:; response_count:1; response_revision:2165; }","duration":"265.202515ms","start":"2026-09-08T15:51:06.996887Z","end":"2026-09-08T15:51:07.26209Z","steps":["trace[620675859] 'agreement among raft nodes before linearized reading' (duration: 264.966499ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:07.262049Z","caller":"traceutil/trace.go:171","msg":"trace[1293535650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2165; }","duration":"126.640597ms","start":"2026-09-08T15:51:07.13539Z","end":"2026-09-08T15:51:07.262031Z","steps":["trace[1293535650] 'agreement among raft nodes before linearized reading' (duration: 126.432651ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:44.916225Z","caller":"traceutil/trace.go:171","msg":"trace[1372217730] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"102.055399ms","start":"2026-09-08T15:51:44.814148Z","end":"2026-09-08T15:51:44.916204Z","steps":["trace[1372217730] 'process raft request' (duration: 101.742752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:52:31.147898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.652897ms","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-09-08T15:52:31.148086Z","caller":"traceutil/trace.go:171","msg":"trace[809814031] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2814; }","duration":"217.927082ms","start":"2026-09-08T15:52:30.930131Z","end":"2026-09-08T15:52:31.148058Z","steps":["trace[809814031] 'count revisions from in-memory index tree' (duration: 217.526634ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:53.310162Z","caller":"traceutil/trace.go:171","msg":"trace[1837525736] linearizableReadLoop","detail":"{readStateIndex:4232; appliedIndex:4231; }","duration":"116.510109ms","start":"2026-09-08T15:55:53.193629Z","end":"2026-09-08T15:55:53.310139Z","steps":["trace[1837525736] 'read index received' (duration: 116.326155ms)","trace[1837525736] 'applied index is now lower than readState.Index' (duration: 183.454µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:55:53.310238Z","caller":"traceutil/trace.go:171","msg":"trace[1096070454] transaction","detail":"{read_only:false; response_revision:4077; number_of_response:1; }","duration":"123.054496ms","start":"2026-09-08T15:55:53.187141Z","end":"2026-09-08T15:55:53.310195Z","steps":["trace[1096070454] 'process raft request' (duration: 122.867541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:55:53.310343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.701014ms","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-09-08T15:55:53.310387Z","caller":"traceutil/trace.go:171","msg":"trace[733590969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4077; }","duration":"116.800317ms","start":"2026-09-08T15:55:53.193578Z","end":"2026-09-08T15:55:53.310379Z","steps":["trace[733590969] 'agreement among raft nodes before linearized reading' (duration: 116.671773ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:55:53.310774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.790556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T15:55:53.310923Z","caller":"traceutil/trace.go:171","msg":"trace[1970565330] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4077; }","duration":"106.019501ms","start":"2026-09-08T15:55:53.204881Z","end":"2026-09-08T15:55:53.3109Z","steps":["trace[1970565330] 'agreement among raft nodes before linearized reading' (duration: 105.601491ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:53.501709Z","caller":"traceutil/trace.go:171","msg":"trace[83310766] linearizableReadLoop","detail":"{readStateIndex:4233; appliedIndex:4232; }","duration":"186.55858ms","start":"2026-09-08T15:55:53.315125Z","end":"2026-09-08T15:55:53.501684Z","steps":["trace[83310766] 'read index received' (duration: 186.419397ms)","trace[83310766] 'applied index is now lower than readState.Index' (duration: 137.103µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:55:53.501757Z","caller":"traceutil/trace.go:171","msg":"trace[1678688150] transaction","detail":"{read_only:false; response_revision:4078; number_of_response:1; }","duration":"187.112884ms","start":"2026-09-08T15:55:53.314618Z","end":"2026-09-08T15:55:53.501731Z","steps":["trace[1678688150] 'process raft request' (duration: 186.891189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:55:53.502045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.893088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T15:55:53.502102Z","caller":"traceutil/trace.go:171","msg":"trace[664885604] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4078; }","duration":"186.984901ms","start":"2026-09-08T15:55:53.315102Z","end":"2026-09-08T15:55:53.502087Z","steps":["trace[664885604] 'agreement among raft nodes before linearized reading' (duration: 186.718434ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:53.504337Z","caller":"traceutil/trace.go:171","msg":"trace[1238343489] transaction","detail":"{read_only:false; response_revision:4079; number_of_response:1; }","duration":"188.703726ms","start":"2026-09-08T15:55:53.315618Z","end":"2026-09-08T15:55:53.504321Z","steps":["trace[1238343489] 'process raft request' (duration: 188.545442ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:55:53.504466Z","caller":"traceutil/trace.go:171","msg":"trace[1181224060] transaction","detail":"{read_only:false; response_revision:4080; number_of_response:1; }","duration":"103.843345ms","start":"2026-09-08T15:55:53.400617Z","end":"2026-09-08T15:55:53.50446Z","steps":["trace[1181224060] 'process raft request' (duration: 103.65704ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:55:53.504598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.888153ms","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-09-08T15:55:53.504621Z","caller":"traceutil/trace.go:171","msg":"trace[1184736003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4080; }","duration":"185.934644ms","start":"2026-09-08T15:55:53.31868Z","end":"2026-09-08T15:55:53.504615Z","steps":["trace[1184736003] 'agreement among raft nodes before linearized reading' (duration: 185.829401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:22.271743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.965163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:57:22.272012Z","caller":"traceutil/trace.go:171","msg":"trace[1451339693] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"117.593852ms","start":"2026-09-08T15:57:22.154395Z","end":"2026-09-08T15:57:22.271989Z","steps":["trace[1451339693] 'process raft request' (duration: 117.50544ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:22.272132Z","caller":"traceutil/trace.go:171","msg":"trace[260285493] linearizableReadLoop","detail":"{readStateIndex:5188; appliedIndex:5185; }","duration":"137.114358ms","start":"2026-09-08T15:57:22.134961Z","end":"2026-09-08T15:57:22.272075Z","steps":["trace[260285493] 'read index received' (duration: 54.731µs)","trace[260285493] 'applied index is now lower than readState.Index' (duration: 137.057326ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:22.272161Z","caller":"traceutil/trace.go:171","msg":"trace[26730661] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"184.088917ms","start":"2026-09-08T15:57:22.088031Z","end":"2026-09-08T15:57:22.27212Z","steps":["trace[26730661] 'process raft request' (duration: 61.620782ms)","trace[26730661] 'compare' (duration: 121.749218ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:22.272317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.366744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:57:22.272439Z","caller":"traceutil/trace.go:171","msg":"trace[1127240005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4995; }","duration":"137.571188ms","start":"2026-09-08T15:57:22.134845Z","end":"2026-09-08T15:57:22.272416Z","steps":["trace[1127240005] 'agreement among raft nodes before linearized reading' (duration: 137.364044ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:22.308733Z","caller":"traceutil/trace.go:171","msg":"trace[1755121025] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"144.014858ms","start":"2026-09-08T15:57:22.164686Z","end":"2026-09-08T15:57:22.308701Z","steps":["trace[1755121025] 'process raft request' (duration: 143.801003ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:28.442946Z","caller":"traceutil/trace.go:171","msg":"trace[1041963038] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"133.564836ms","start":"2026-09-08T15:57:28.309367Z","end":"2026-09-08T15:57:28.442932Z","steps":["trace[1041963038] 'process raft request' (duration: 130.319659ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:28.443107Z","caller":"traceutil/trace.go:171","msg":"trace[343322148] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"132.241704ms","start":"2026-09-08T15:57:28.310833Z","end":"2026-09-08T15:57:28.443075Z","steps":["trace[343322148] 'process raft request' (duration: 131.993947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:32.410282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.20705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:57:32.410382Z","caller":"traceutil/trace.go:171","msg":"trace[949442126] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"202.150028ms","start":"2026-09-08T15:57:32.208215Z","end":"2026-09-08T15:57:32.410365Z","steps":["trace[949442126] 'process raft request' (duration: 73.798795ms)","trace[949442126] 'compare' (duration: 128.065897ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:41.384581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.866411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-09-08T15:57:41.384741Z","caller":"traceutil/trace.go:171","msg":"trace[1104467086] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5494; }","duration":"103.978443ms","start":"2026-09-08T15:57:41.280676Z","end":"2026-09-08T15:57:41.384654Z","steps":["trace[1104467086] 'range keys from in-memory index tree' (duration: 103.656065ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:41.389101Z","caller":"traceutil/trace.go:171","msg":"trace[1562434581] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"104.776811ms","start":"2026-09-08T15:57:41.284308Z","end":"2026-09-08T15:57:41.389084Z","steps":["trace[1562434581] 'process raft request' (duration: 104.617098ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:41.556978Z","caller":"traceutil/trace.go:171","msg":"trace[765381749] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"148.272909ms","start":"2026-09-08T15:57:41.408677Z","end":"2026-09-08T15:57:41.55695Z","steps":["trace[765381749] 'process raft request' (duration: 95.805421ms)","trace[765381749] 'compare' (duration: 52.353985ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:57:41.966929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.112291ms","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-09-08T15:57:41.967068Z","caller":"traceutil/trace.go:171","msg":"trace[259082397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5505; }","duration":"117.318805ms","start":"2026-09-08T15:57:41.849717Z","end":"2026-09-08T15:57:41.967036Z","steps":["trace[259082397] 'range keys from in-memory index tree' (duration: 116.864664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:43.929871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.432755ms","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-09-08T15:57:43.929944Z","caller":"traceutil/trace.go:171","msg":"trace[1385676739] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5543; }","duration":"119.552248ms","start":"2026-09-08T15:57:43.810379Z","end":"2026-09-08T15:57:43.929932Z","steps":["trace[1385676739] 'range keys from in-memory index tree' (duration: 119.28824ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.522831Z","caller":"traceutil/trace.go:171","msg":"trace[549427308] linearizableReadLoop","detail":"{readStateIndex:5926; appliedIndex:5925; }","duration":"255.709512ms","start":"2026-09-08T15:57:52.267088Z","end":"2026-09-08T15:57:52.522797Z","steps":["trace[549427308] 'read index received' (duration: 255.377574ms)","trace[549427308] 'applied index is now lower than readState.Index' (duration: 330.308µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:52.522939Z","caller":"traceutil/trace.go:171","msg":"trace[2111611260] transaction","detail":"{read_only:false; response_revision:5713; number_of_response:1; }","duration":"268.049381ms","start":"2026-09-08T15:57:52.254841Z","end":"2026-09-08T15:57:52.522891Z","steps":["trace[2111611260] 'process raft request' (duration: 267.751764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:52.523138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.01972ms","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-09-08T15:57:52.523166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.769779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-08T15:57:52.523338Z","caller":"traceutil/trace.go:171","msg":"trace[1290973650] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5713; }","duration":"236.972994ms","start":"2026-09-08T15:57:52.286296Z","end":"2026-09-08T15:57:52.523269Z","steps":["trace[1290973650] 'agreement among raft nodes before linearized reading' (duration: 236.709748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:52.523427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.930486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-09-08T15:57:52.5232Z","caller":"traceutil/trace.go:171","msg":"trace[375686822] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5713; }","duration":"256.135252ms","start":"2026-09-08T15:57:52.26705Z","end":"2026-09-08T15:57:52.523186Z","steps":["trace[375686822] 'agreement among raft nodes before linearized reading' (duration: 255.915937ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.523557Z","caller":"traceutil/trace.go:171","msg":"trace[2081452503] range","detail":"{range_begin:/registry/pods/monitoring/loki-chunks-cache-0; range_end:; response_count:1; response_revision:5713; }","duration":"126.128681ms","start":"2026-09-08T15:57:52.397404Z","end":"2026-09-08T15:57:52.523533Z","steps":["trace[2081452503] 'agreement among raft nodes before linearized reading' (duration: 125.755792ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.639356Z","caller":"traceutil/trace.go:171","msg":"trace[2061027603] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"110.878404ms","start":"2026-09-08T15:57:52.52843Z","end":"2026-09-08T15:57:52.639309Z","steps":["trace[2061027603] 'process raft request' (duration: 88.226034ms)","trace[2061027603] 'compare' (duration: 22.401234ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:52.659124Z","caller":"traceutil/trace.go:171","msg":"trace[1286776657] transaction","detail":"{read_only:false; response_revision:5715; number_of_response:1; }","duration":"129.361136ms","start":"2026-09-08T15:57:52.529733Z","end":"2026-09-08T15:57:52.659094Z","steps":["trace[1286776657] 'process raft request' (duration: 129.0944ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.659644Z","caller":"traceutil/trace.go:171","msg":"trace[1924221052] transaction","detail":"{read_only:false; response_revision:5716; number_of_response:1; }","duration":"126.713784ms","start":"2026-09-08T15:57:52.532913Z","end":"2026-09-08T15:57:52.659627Z","steps":["trace[1924221052] 'process raft request' (duration: 126.115481ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:52.89834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.648909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:57:52.898477Z","caller":"traceutil/trace.go:171","msg":"trace[661855085] transaction","detail":"{read_only:false; response_revision:5718; number_of_response:1; }","duration":"232.910509ms","start":"2026-09-08T15:57:52.665539Z","end":"2026-09-08T15:57:52.898449Z","steps":["trace[661855085] 'process raft request' (duration: 131.050396ms)","trace[661855085] 'compare' (duration: 101.513305ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:52.903776Z","caller":"traceutil/trace.go:171","msg":"trace[1547175488] transaction","detail":"{read_only:false; response_revision:5720; number_of_response:1; }","duration":"235.974731ms","start":"2026-09-08T15:57:52.667775Z","end":"2026-09-08T15:57:52.903749Z","steps":["trace[1547175488] 'process raft request' (duration: 235.784737ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.903799Z","caller":"traceutil/trace.go:171","msg":"trace[963076730] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"235.749966ms","start":"2026-09-08T15:57:52.668028Z","end":"2026-09-08T15:57:52.903778Z","steps":["trace[963076730] 'process raft request' (duration: 235.598502ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.904032Z","caller":"traceutil/trace.go:171","msg":"trace[1049920600] transaction","detail":"{read_only:false; response_revision:5719; number_of_response:1; }","duration":"236.864671ms","start":"2026-09-08T15:57:52.667046Z","end":"2026-09-08T15:57:52.903911Z","steps":["trace[1049920600] 'process raft request' (duration: 236.334189ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.904302Z","caller":"traceutil/trace.go:171","msg":"trace[226433500] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"235.842557ms","start":"2026-09-08T15:57:52.668431Z","end":"2026-09-08T15:57:52.904273Z","steps":["trace[226433500] 'process raft request' (duration: 235.260074ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.904817Z","caller":"traceutil/trace.go:171","msg":"trace[903384199] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"235.797397ms","start":"2026-09-08T15:57:52.668994Z","end":"2026-09-08T15:57:52.904791Z","steps":["trace[903384199] 'process raft request' (duration: 235.033158ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.905018Z","caller":"traceutil/trace.go:171","msg":"trace[1286535284] transaction","detail":"{read_only:false; response_revision:5726; number_of_response:1; }","duration":"230.981093ms","start":"2026-09-08T15:57:52.674016Z","end":"2026-09-08T15:57:52.904997Z","steps":["trace[1286535284] 'process raft request' (duration: 230.856941ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.905334Z","caller":"traceutil/trace.go:171","msg":"trace[1023559672] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"233.525254ms","start":"2026-09-08T15:57:52.671792Z","end":"2026-09-08T15:57:52.905317Z","steps":["trace[1023559672] 'process raft request' (duration: 232.992132ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:52.905585Z","caller":"traceutil/trace.go:171","msg":"trace[1141296794] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"235.955299ms","start":"2026-09-08T15:57:52.669614Z","end":"2026-09-08T15:57:52.905569Z","steps":["trace[1141296794] 'process raft request' (duration: 235.09229ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:53.294543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2939} {"level":"info","ts":"2026-09-08T15:57:53.38146Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2939,"took":"85.48776ms","hash":1185586302,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T15:57:53.381566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1185586302,"revision":2939,"compact-revision":-1} {"level":"info","ts":"2026-09-08T15:57:54.816121Z","caller":"traceutil/trace.go:171","msg":"trace[420341804] transaction","detail":"{read_only:false; response_revision:5735; number_of_response:1; }","duration":"192.125525ms","start":"2026-09-08T15:57:54.623942Z","end":"2026-09-08T15:57:54.816067Z","steps":["trace[420341804] 'process raft request' (duration: 181.892695ms)","trace[420341804] 'compare' (duration: 10.043656ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:54.81918Z","caller":"traceutil/trace.go:171","msg":"trace[2041765681] transaction","detail":"{read_only:false; response_revision:5737; number_of_response:1; }","duration":"194.489659ms","start":"2026-09-08T15:57:54.624671Z","end":"2026-09-08T15:57:54.819161Z","steps":["trace[2041765681] 'process raft request' (duration: 194.427078ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:54.819263Z","caller":"traceutil/trace.go:171","msg":"trace[1100620694] linearizableReadLoop","detail":"{readStateIndex:5951; appliedIndex:5948; }","duration":"175.28296ms","start":"2026-09-08T15:57:54.643966Z","end":"2026-09-08T15:57:54.819249Z","steps":["trace[1100620694] 'read index received' (duration: 161.839965ms)","trace[1100620694] 'applied index is now lower than readState.Index' (duration: 13.439165ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:57:54.819182Z","caller":"traceutil/trace.go:171","msg":"trace[1399546356] transaction","detail":"{read_only:false; response_revision:5736; number_of_response:1; }","duration":"194.559621ms","start":"2026-09-08T15:57:54.624597Z","end":"2026-09-08T15:57:54.819157Z","steps":["trace[1399546356] 'process raft request' (duration: 194.394408ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:57:54.819458Z","caller":"traceutil/trace.go:171","msg":"trace[1797058921] transaction","detail":"{read_only:false; response_revision:5738; number_of_response:1; }","duration":"153.766137ms","start":"2026-09-08T15:57:54.665681Z","end":"2026-09-08T15:57:54.819447Z","steps":["trace[1797058921] 'process raft request' (duration: 153.44526ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:57:54.81964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.665679ms","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-09-08T15:57:54.819671Z","caller":"traceutil/trace.go:171","msg":"trace[120360926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5738; }","duration":"175.71548ms","start":"2026-09-08T15:57:54.643949Z","end":"2026-09-08T15:57:54.819664Z","steps":["trace[120360926] 'agreement among raft nodes before linearized reading' (duration: 175.594657ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:45.592322Z","caller":"traceutil/trace.go:171","msg":"trace[924136310] transaction","detail":"{read_only:false; response_revision:6255; number_of_response:1; }","duration":"164.898328ms","start":"2026-09-08T15:58:45.427385Z","end":"2026-09-08T15:58:45.592283Z","steps":["trace[924136310] 'process raft request' (duration: 164.55673ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:02:05.942643Z","caller":"traceutil/trace.go:171","msg":"trace[299556839] transaction","detail":"{read_only:false; response_revision:7587; number_of_response:1; }","duration":"106.567938ms","start":"2026-09-08T16:02:05.836043Z","end":"2026-09-08T16:02:05.942611Z","steps":["trace[299556839] 'process raft request' (duration: 106.342103ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:02:06.145634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.418964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:02:06.145901Z","caller":"traceutil/trace.go:171","msg":"trace[1535481947] transaction","detail":"{read_only:false; response_revision:7588; number_of_response:1; }","duration":"224.207812ms","start":"2026-09-08T16:02:05.921636Z","end":"2026-09-08T16:02:06.145843Z","steps":["trace[1535481947] 'process raft request' (duration: 98.481031ms)","trace[1535481947] 'compare' (duration: 125.198209ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:02:06.145928Z","caller":"traceutil/trace.go:171","msg":"trace[336667328] transaction","detail":"{read_only:false; response_revision:7589; number_of_response:1; }","duration":"166.985992ms","start":"2026-09-08T16:02:05.978915Z","end":"2026-09-08T16:02:06.145901Z","steps":["trace[336667328] 'process raft request' (duration: 166.839979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:02:07.349492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.282273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:02:07.349622Z","caller":"traceutil/trace.go:171","msg":"trace[2029335880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7593; }","duration":"214.485148ms","start":"2026-09-08T16:02:07.135108Z","end":"2026-09-08T16:02:07.349593Z","steps":["trace[2029335880] 'range keys from in-memory index tree' (duration: 214.196151ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:02:07.37242Z","caller":"traceutil/trace.go:171","msg":"trace[797302515] linearizableReadLoop","detail":"{readStateIndex:7871; appliedIndex:7870; }","duration":"127.785318ms","start":"2026-09-08T16:02:07.244612Z","end":"2026-09-08T16:02:07.372398Z","steps":["trace[797302515] 'read index received' (duration: 127.562803ms)","trace[797302515] 'applied index is now lower than readState.Index' (duration: 221.575µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:02:07.372546Z","caller":"traceutil/trace.go:171","msg":"trace[527020129] transaction","detail":"{read_only:false; response_revision:7594; number_of_response:1; }","duration":"211.08546ms","start":"2026-09-08T16:02:07.161402Z","end":"2026-09-08T16:02:07.372487Z","steps":["trace[527020129] 'process raft request' (duration: 210.843174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:02:07.372813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.155546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-09-08T16:02:07.372927Z","caller":"traceutil/trace.go:171","msg":"trace[472474897] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-barbican; range_end:; response_count:1; response_revision:7594; }","duration":"128.33121ms","start":"2026-09-08T16:02:07.244567Z","end":"2026-09-08T16:02:07.372898Z","steps":["trace[472474897] 'agreement among raft nodes before linearized reading' (duration: 127.950581ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:02:37.359531Z","caller":"traceutil/trace.go:171","msg":"trace[608376923] transaction","detail":"{read_only:false; response_revision:7874; number_of_response:1; }","duration":"126.053578ms","start":"2026-09-08T16:02:37.233444Z","end":"2026-09-08T16:02:37.359498Z","steps":["trace[608376923] 'process raft request' (duration: 83.931626ms)","trace[608376923] 'compare' (duration: 41.874416ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:02:37.634548Z","caller":"traceutil/trace.go:171","msg":"trace[1748163685] transaction","detail":"{read_only:false; response_revision:7875; number_of_response:1; }","duration":"267.115962ms","start":"2026-09-08T16:02:37.367407Z","end":"2026-09-08T16:02:37.634523Z","steps":["trace[1748163685] 'process raft request' (duration: 238.543552ms)","trace[1748163685] 'compare' (duration: 27.985276ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:02:37.701608Z","caller":"traceutil/trace.go:171","msg":"trace[1541228548] transaction","detail":"{read_only:false; response_revision:7876; number_of_response:1; }","duration":"300.480262ms","start":"2026-09-08T16:02:37.401095Z","end":"2026-09-08T16:02:37.701576Z","steps":["trace[1541228548] 'process raft request' (duration: 300.183834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:02:37.701799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:02:37.401088Z","time spent":"300.618505ms","remote":"127.0.0.1:37276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20227,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:02:37.701729Z","caller":"traceutil/trace.go:171","msg":"trace[1174848038] linearizableReadLoop","detail":"{readStateIndex:8161; appliedIndex:8158; }","duration":"128.485454ms","start":"2026-09-08T16:02:37.573213Z","end":"2026-09-08T16:02:37.701699Z","steps":["trace[1174848038] 'read index received' (duration: 32.797937ms)","trace[1174848038] 'applied index is now lower than readState.Index' (duration: 95.682867ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:02:37.702172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.963795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f9bbk\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-09-08T16:02:37.70223Z","caller":"traceutil/trace.go:171","msg":"trace[1370138238] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f9bbk; range_end:; response_count:1; response_revision:7877; }","duration":"129.104538ms","start":"2026-09-08T16:02:37.573108Z","end":"2026-09-08T16:02:37.702213Z","steps":["trace[1370138238] 'agreement among raft nodes before linearized reading' (duration: 128.831172ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:02:37.702105Z","caller":"traceutil/trace.go:171","msg":"trace[783069231] transaction","detail":"{read_only:false; response_revision:7877; number_of_response:1; }","duration":"190.057035ms","start":"2026-09-08T16:02:37.511969Z","end":"2026-09-08T16:02:37.702026Z","steps":["trace[783069231] 'process raft request' (duration: 189.519963ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:02:53.302502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5727} {"level":"info","ts":"2026-09-08T16:02:53.403148Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5727,"took":"98.604504ms","hash":3681429974,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":26603520,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T16:02:53.40322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3681429974,"revision":5727,"compact-revision":2939} {"level":"info","ts":"2026-09-08T16:04:06.799961Z","caller":"traceutil/trace.go:171","msg":"trace[1526624022] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"108.448984ms","start":"2026-09-08T16:04:06.691481Z","end":"2026-09-08T16:04:06.79993Z","steps":["trace[1526624022] 'process raft request' (duration: 108.231519ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:06.815528Z","caller":"traceutil/trace.go:171","msg":"trace[649302256] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"120.30598ms","start":"2026-09-08T16:04:06.695177Z","end":"2026-09-08T16:04:06.815483Z","steps":["trace[649302256] 'process raft request' (duration: 120.011223ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:08.649364Z","caller":"traceutil/trace.go:171","msg":"trace[1689734503] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"226.694897ms","start":"2026-09-08T16:04:08.422616Z","end":"2026-09-08T16:04:08.649311Z","steps":["trace[1689734503] 'process raft request' (duration: 226.505943ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:08.85506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.694868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:04:08.855333Z","caller":"traceutil/trace.go:171","msg":"trace[608146488] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"424.147846ms","start":"2026-09-08T16:04:08.43114Z","end":"2026-09-08T16:04:08.855288Z","steps":["trace[608146488] 'process raft request' (duration: 318.997168ms)","trace[608146488] 'compare' (duration: 104.457582ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:04:08.855434Z","caller":"traceutil/trace.go:171","msg":"trace[965015922] linearizableReadLoop","detail":"{readStateIndex:8924; appliedIndex:8922; }","duration":"383.471803ms","start":"2026-09-08T16:04:08.471909Z","end":"2026-09-08T16:04:08.855381Z","steps":["trace[965015922] 'read index received' (duration: 177.17828ms)","trace[965015922] 'applied index is now lower than readState.Index' (duration: 206.290123ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:08.855663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:08.431114Z","time spent":"424.37149ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:04:08.855718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.79185ms","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-09-08T16:04:08.855804Z","caller":"traceutil/trace.go:171","msg":"trace[344975582] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8617; }","duration":"383.939473ms","start":"2026-09-08T16:04:08.471843Z","end":"2026-09-08T16:04:08.855782Z","steps":["trace[344975582] 'agreement among raft nodes before linearized reading' (duration: 383.682917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:08.855845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:08.471818Z","time spent":"384.018605ms","remote":"127.0.0.1:37398","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-09-08T16:04:08.857855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.538071ms","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-09-08T16:04:08.857942Z","caller":"traceutil/trace.go:171","msg":"trace[1171283067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8618; }","duration":"379.631083ms","start":"2026-09-08T16:04:08.478289Z","end":"2026-09-08T16:04:08.85792Z","steps":["trace[1171283067] 'agreement among raft nodes before linearized reading' (duration: 379.381478ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:08.857993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:08.47827Z","time spent":"379.710866ms","remote":"127.0.0.1:37398","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-09-08T16:04:21.347288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.170138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:04:21.347488Z","caller":"traceutil/trace.go:171","msg":"trace[421092431] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"376.06287ms","start":"2026-09-08T16:04:20.971397Z","end":"2026-09-08T16:04:21.34746Z","steps":["trace[421092431] 'process raft request' (duration: 104.586985ms)","trace[421092431] 'compare' (duration: 270.978874ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:21.347597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:20.971364Z","time spent":"376.174924ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:04:21.365658Z","caller":"traceutil/trace.go:171","msg":"trace[1458052426] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"384.797263ms","start":"2026-09-08T16:04:20.980844Z","end":"2026-09-08T16:04:21.365641Z","steps":["trace[1458052426] 'process raft request' (duration: 384.554318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:21.365838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:20.980812Z","time spent":"384.896326ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:04:21.366008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.005977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T16:04:21.366085Z","caller":"traceutil/trace.go:171","msg":"trace[235297980] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8681; }","duration":"372.14577ms","start":"2026-09-08T16:04:20.993919Z","end":"2026-09-08T16:04:21.366065Z","steps":["trace[235297980] 'agreement among raft nodes before linearized reading' (duration: 371.864083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:21.36613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:20.993873Z","time spent":"372.248552ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-08T16:04:21.365576Z","caller":"traceutil/trace.go:171","msg":"trace[2085665365] linearizableReadLoop","detail":"{readStateIndex:8991; appliedIndex:8989; }","duration":"371.559826ms","start":"2026-09-08T16:04:20.99399Z","end":"2026-09-08T16:04:21.36555Z","steps":["trace[2085665365] 'read index received' (duration: 82.027442ms)","trace[2085665365] 'applied index is now lower than readState.Index' (duration: 289.531584ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:21.366501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.073209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:21.366544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.334248ms","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-09-08T16:04:21.366596Z","caller":"traceutil/trace.go:171","msg":"trace[150178373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8681; }","duration":"119.437701ms","start":"2026-09-08T16:04:21.247143Z","end":"2026-09-08T16:04:21.366581Z","steps":["trace[150178373] 'agreement among raft nodes before linearized reading' (duration: 119.256037ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:21.366596Z","caller":"traceutil/trace.go:171","msg":"trace[2071701629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8681; }","duration":"231.245162ms","start":"2026-09-08T16:04:21.13533Z","end":"2026-09-08T16:04:21.366576Z","steps":["trace[2071701629] 'agreement among raft nodes before linearized reading' (duration: 231.100149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:21.366529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.287491ms","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-09-08T16:04:21.36669Z","caller":"traceutil/trace.go:171","msg":"trace[521541577] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8681; }","duration":"117.466464ms","start":"2026-09-08T16:04:21.249207Z","end":"2026-09-08T16:04:21.366673Z","steps":["trace[521541577] 'agreement among raft nodes before linearized reading' (duration: 117.173318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:21.366639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.173426ms","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-09-08T16:04:21.367013Z","caller":"traceutil/trace.go:171","msg":"trace[950796778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8681; }","duration":"358.614267ms","start":"2026-09-08T16:04:21.008385Z","end":"2026-09-08T16:04:21.366999Z","steps":["trace[950796778] 'agreement among raft nodes before linearized reading' (duration: 357.941221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:21.367047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:21.00836Z","time spent":"358.676847ms","remote":"127.0.0.1:37398","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":"info","ts":"2026-09-08T16:04:47.166926Z","caller":"traceutil/trace.go:171","msg":"trace[1618825065] transaction","detail":"{read_only:false; response_revision:8869; number_of_response:1; }","duration":"101.951985ms","start":"2026-09-08T16:04:47.064887Z","end":"2026-09-08T16:04:47.166839Z","steps":["trace[1618825065] 'process raft request' (duration: 101.7347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:50.779762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.412936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-08T16:04:50.779905Z","caller":"traceutil/trace.go:171","msg":"trace[1003826474] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:8890; }","duration":"137.682872ms","start":"2026-09-08T16:04:50.6422Z","end":"2026-09-08T16:04:50.779883Z","steps":["trace[1003826474] 'count revisions from in-memory index tree' (duration: 137.227032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:57.516637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.538042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:04:57.516798Z","caller":"traceutil/trace.go:171","msg":"trace[1913608153] linearizableReadLoop","detail":"{readStateIndex:9249; appliedIndex:9248; }","duration":"178.0978ms","start":"2026-09-08T16:04:57.338671Z","end":"2026-09-08T16:04:57.516768Z","steps":["trace[1913608153] 'read index received' (duration: 67.119115ms)","trace[1913608153] 'applied index is now lower than readState.Index' (duration: 110.976895ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:04:57.516949Z","caller":"traceutil/trace.go:171","msg":"trace[1949529262] transaction","detail":"{read_only:false; response_revision:8928; number_of_response:1; }","duration":"268.107198ms","start":"2026-09-08T16:04:57.248824Z","end":"2026-09-08T16:04:57.516932Z","steps":["trace[1949529262] 'process raft request' (duration: 157.050283ms)","trace[1949529262] 'compare' (duration: 110.4065ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:57.517403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.735184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-09-08T16:04:57.518167Z","caller":"traceutil/trace.go:171","msg":"trace[1678866599] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8928; }","duration":"179.521413ms","start":"2026-09-08T16:04:57.338596Z","end":"2026-09-08T16:04:57.518118Z","steps":["trace[1678866599] 'agreement among raft nodes before linearized reading' (duration: 178.645962ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:04.444238Z","caller":"traceutil/trace.go:171","msg":"trace[220750234] transaction","detail":"{read_only:false; response_revision:9003; number_of_response:1; }","duration":"112.992889ms","start":"2026-09-08T16:05:04.331205Z","end":"2026-09-08T16:05:04.444198Z","steps":["trace[220750234] 'process raft request' (duration: 112.783425ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:05.14258Z","caller":"traceutil/trace.go:171","msg":"trace[2039548352] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"143.063417ms","start":"2026-09-08T16:05:04.999486Z","end":"2026-09-08T16:05:05.142549Z","steps":["trace[2039548352] 'process raft request' (duration: 142.580636ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:05.448678Z","caller":"traceutil/trace.go:171","msg":"trace[120347201] transaction","detail":"{read_only:false; response_revision:9008; number_of_response:1; }","duration":"149.349744ms","start":"2026-09-08T16:05:05.299294Z","end":"2026-09-08T16:05:05.448643Z","steps":["trace[120347201] 'process raft request' (duration: 149.059537ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:05.499696Z","caller":"traceutil/trace.go:171","msg":"trace[2043384419] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"169.574892ms","start":"2026-09-08T16:05:05.330086Z","end":"2026-09-08T16:05:05.499661Z","steps":["trace[2043384419] 'process raft request' (duration: 169.396748ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:27.060799Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000255c.wal"} {"level":"info","ts":"2026-09-08T16:06:16.138597Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:06:16.14573Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-08T16:06:16.145914Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-08T16:07:53.309355Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7989} {"level":"info","ts":"2026-09-08T16:07:53.385948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7989,"took":"73.916131ms","hash":3019337102,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":28164096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T16:07:53.386041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3019337102,"revision":7989,"compact-revision":5727} {"level":"info","ts":"2026-09-08T16:08:41.176324Z","caller":"traceutil/trace.go:171","msg":"trace[233697288] linearizableReadLoop","detail":"{readStateIndex:11215; appliedIndex:11214; }","duration":"140.894971ms","start":"2026-09-08T16:08:41.035382Z","end":"2026-09-08T16:08:41.176277Z","steps":["trace[233697288] 'read index received' (duration: 140.660094ms)","trace[233697288] 'applied index is now lower than readState.Index' (duration: 233.277µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:08:41.176415Z","caller":"traceutil/trace.go:171","msg":"trace[501207493] transaction","detail":"{read_only:false; response_revision:10835; number_of_response:1; }","duration":"147.182096ms","start":"2026-09-08T16:08:41.029156Z","end":"2026-09-08T16:08:41.176338Z","steps":["trace[501207493] 'process raft request' (duration: 146.841207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:08:41.176727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.31655ms","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-09-08T16:08:41.176836Z","caller":"traceutil/trace.go:171","msg":"trace[953720144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10835; }","duration":"141.485824ms","start":"2026-09-08T16:08:41.035326Z","end":"2026-09-08T16:08:41.176812Z","steps":["trace[953720144] 'agreement among raft nodes before linearized reading' (duration: 141.147976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:08:41.180443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.910811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-api-68c6b6cdcc-vkmtz\" ","response":"range_response_count:1 size:7416"} {"level":"info","ts":"2026-09-08T16:08:41.180496Z","caller":"traceutil/trace.go:171","msg":"trace[1263466858] range","detail":"{range_begin:/registry/pods/openstack/staffeln-api-68c6b6cdcc-vkmtz; range_end:; response_count:1; response_revision:10836; }","duration":"141.001453ms","start":"2026-09-08T16:08:41.039481Z","end":"2026-09-08T16:08:41.180483Z","steps":["trace[1263466858] 'agreement among raft nodes before linearized reading' (duration: 140.828809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:08:41.180813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.673148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T16:08:41.180871Z","caller":"traceutil/trace.go:171","msg":"trace[1926773138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10836; }","duration":"128.755249ms","start":"2026-09-08T16:08:41.052102Z","end":"2026-09-08T16:08:41.180857Z","steps":["trace[1926773138] 'agreement among raft nodes before linearized reading' (duration: 128.626846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:08:41.181056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.544259ms","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-09-08T16:08:41.181122Z","caller":"traceutil/trace.go:171","msg":"trace[291051442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10836; }","duration":"123.670482ms","start":"2026-09-08T16:08:41.057433Z","end":"2026-09-08T16:08:41.181103Z","steps":["trace[291051442] 'agreement among raft nodes before linearized reading' (duration: 123.436547ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:07.979234Z","caller":"traceutil/trace.go:171","msg":"trace[1990200205] linearizableReadLoop","detail":"{readStateIndex:12048; appliedIndex:12047; }","duration":"151.946962ms","start":"2026-09-08T16:10:07.827258Z","end":"2026-09-08T16:10:07.979205Z","steps":["trace[1990200205] 'read index received' (duration: 151.686276ms)","trace[1990200205] 'applied index is now lower than readState.Index' (duration: 259.406µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:10:07.979325Z","caller":"traceutil/trace.go:171","msg":"trace[316009586] transaction","detail":"{read_only:false; response_revision:11648; number_of_response:1; }","duration":"157.09606ms","start":"2026-09-08T16:10:07.822195Z","end":"2026-09-08T16:10:07.979291Z","steps":["trace[316009586] 'process raft request' (duration: 156.817363ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:07.979631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.70125ms","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-09-08T16:10:07.979662Z","caller":"traceutil/trace.go:171","msg":"trace[1344163179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11648; }","duration":"149.783022ms","start":"2026-09-08T16:10:07.82987Z","end":"2026-09-08T16:10:07.979653Z","steps":["trace[1344163179] 'agreement among raft nodes before linearized reading' (duration: 149.643149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:07.979696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.2954ms","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-09-08T16:10:07.979766Z","caller":"traceutil/trace.go:171","msg":"trace[636022034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11648; }","duration":"152.557886ms","start":"2026-09-08T16:10:07.827187Z","end":"2026-09-08T16:10:07.979745Z","steps":["trace[636022034] 'agreement among raft nodes before linearized reading' (duration: 152.220729ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:07.979854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.247599ms","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-09-08T16:10:07.979991Z","caller":"traceutil/trace.go:171","msg":"trace[248574990] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11648; }","duration":"145.424153ms","start":"2026-09-08T16:10:07.834532Z","end":"2026-09-08T16:10:07.979956Z","steps":["trace[248574990] 'agreement among raft nodes before linearized reading' (duration: 145.031194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:07.980155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.367932ms","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-09-08T16:10:07.980293Z","caller":"traceutil/trace.go:171","msg":"trace[869452639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11648; }","duration":"149.544166ms","start":"2026-09-08T16:10:07.830722Z","end":"2026-09-08T16:10:07.980266Z","steps":["trace[869452639] 'agreement among raft nodes before linearized reading' (duration: 149.123127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:08.23076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.050934ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:10:08.230998Z","caller":"traceutil/trace.go:171","msg":"trace[1447769904] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:11648; }","duration":"121.35278ms","start":"2026-09-08T16:10:08.109617Z","end":"2026-09-08T16:10:08.23097Z","steps":["trace[1447769904] 'range keys from in-memory index tree' (duration: 121.019403ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:08.231076Z","caller":"traceutil/trace.go:171","msg":"trace[1947265986] transaction","detail":"{read_only:false; response_revision:11649; number_of_response:1; }","duration":"246.291443ms","start":"2026-09-08T16:10:07.984749Z","end":"2026-09-08T16:10:08.231041Z","steps":["trace[1947265986] 'process raft request' (duration: 219.675673ms)","trace[1947265986] 'compare' (duration: 26.417325ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:10:08.410286Z","caller":"traceutil/trace.go:171","msg":"trace[319775166] transaction","detail":"{read_only:false; response_revision:11652; number_of_response:1; }","duration":"424.793225ms","start":"2026-09-08T16:10:07.985464Z","end":"2026-09-08T16:10:08.410257Z","steps":["trace[319775166] 'process raft request' (duration: 424.709003ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:08.410442Z","caller":"traceutil/trace.go:171","msg":"trace[1585209945] transaction","detail":"{read_only:false; response_revision:11650; number_of_response:1; }","duration":"424.98445ms","start":"2026-09-08T16:10:07.985368Z","end":"2026-09-08T16:10:08.410352Z","steps":["trace[1585209945] 'process raft request' (duration: 394.014269ms)","trace[1585209945] 'compare' (duration: 30.53644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:10:08.410489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.176629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:10:08.410552Z","caller":"traceutil/trace.go:171","msg":"trace[1520551409] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11653; }","duration":"389.310661ms","start":"2026-09-08T16:10:08.021227Z","end":"2026-09-08T16:10:08.410538Z","steps":["trace[1520551409] 'agreement among raft nodes before linearized reading' (duration: 389.147917ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:10:08.410534Z","caller":"traceutil/trace.go:171","msg":"trace[1583926306] transaction","detail":"{read_only:false; response_revision:11651; number_of_response:1; }","duration":"424.971909ms","start":"2026-09-08T16:10:07.985486Z","end":"2026-09-08T16:10:08.410458Z","steps":["trace[1583926306] 'process raft request' (duration: 424.59252ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:08.410594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:10:08.021202Z","time spent":"389.381343ms","remote":"127.0.0.1:59368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-09-08T16:10:08.410335Z","caller":"traceutil/trace.go:171","msg":"trace[155064673] linearizableReadLoop","detail":"{readStateIndex:12053; appliedIndex:12048; }","duration":"389.023155ms","start":"2026-09-08T16:10:08.021282Z","end":"2026-09-08T16:10:08.410305Z","steps":["trace[155064673] 'read index received' (duration: 183.161916ms)","trace[155064673] 'applied index is now lower than readState.Index' (duration: 205.860219ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:10:08.410601Z","caller":"traceutil/trace.go:171","msg":"trace[339903118] transaction","detail":"{read_only:false; response_revision:11653; number_of_response:1; }","duration":"411.800057ms","start":"2026-09-08T16:10:07.998751Z","end":"2026-09-08T16:10:08.410551Z","steps":["trace[339903118] 'process raft request' (duration: 411.464269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:10:08.410666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:10:07.985435Z","time spent":"425.055151ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:10:08.410757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:10:07.985479Z","time spent":"425.155533ms","remote":"127.0.0.1:37398","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-09-08T16:10:08.410835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:10:07.998731Z","time spent":"411.986511ms","remote":"127.0.0.1:37398","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-09-08T16:10:08.41082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.098355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:10:08.410902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:10:07.985341Z","time spent":"425.272086ms","remote":"127.0.0.1:37398","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-09-08T16:10:08.410985Z","caller":"traceutil/trace.go:171","msg":"trace[1280165219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11653; }","duration":"275.320879ms","start":"2026-09-08T16:10:08.135647Z","end":"2026-09-08T16:10:08.410968Z","steps":["trace[1280165219] 'agreement among raft nodes before linearized reading' (duration: 275.088194ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:12:53.316349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10414} {"level":"info","ts":"2026-09-08T16:12:53.410687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10414,"took":"91.268183ms","hash":2791914804,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":27394048,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T16:12:53.410808Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2791914804,"revision":10414,"compact-revision":7989} {"level":"warn","ts":"2026-09-08T16:16:02.325095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.007709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:16:02.325221Z","caller":"traceutil/trace.go:171","msg":"trace[1824544481] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:14061; }","duration":"139.177133ms","start":"2026-09-08T16:16:02.186015Z","end":"2026-09-08T16:16:02.325192Z","steps":["trace[1824544481] 'count revisions from in-memory index tree' (duration: 138.935888ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:16:07.539198Z","caller":"traceutil/trace.go:171","msg":"trace[661879813] transaction","detail":"{read_only:false; response_revision:14096; number_of_response:1; }","duration":"108.133515ms","start":"2026-09-08T16:16:07.431038Z","end":"2026-09-08T16:16:07.539172Z","steps":["trace[661879813] 'process raft request' (duration: 107.972531ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:16:46.965764Z","caller":"traceutil/trace.go:171","msg":"trace[896619980] linearizableReadLoop","detail":"{readStateIndex:14806; appliedIndex:14805; }","duration":"156.31274ms","start":"2026-09-08T16:16:46.809415Z","end":"2026-09-08T16:16:46.965728Z","steps":["trace[896619980] 'read index received' (duration: 98.07927ms)","trace[896619980] 'applied index is now lower than readState.Index' (duration: 58.23172ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:16:46.966099Z","caller":"traceutil/trace.go:171","msg":"trace[340590426] transaction","detail":"{read_only:false; response_revision:14312; number_of_response:1; }","duration":"202.804685ms","start":"2026-09-08T16:16:46.763271Z","end":"2026-09-08T16:16:46.966076Z","steps":["trace[340590426] 'process raft request' (duration: 144.206007ms)","trace[340590426] 'compare' (duration: 57.792699ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:16:46.966484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.027126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-sfwgx\" ","response":"range_response_count:1 size:12356"} {"level":"info","ts":"2026-09-08T16:16:46.966526Z","caller":"traceutil/trace.go:171","msg":"trace[1395993815] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-sfwgx; range_end:; response_count:1; response_revision:14312; }","duration":"157.164999ms","start":"2026-09-08T16:16:46.809352Z","end":"2026-09-08T16:16:46.966517Z","steps":["trace[1395993815] 'agreement among raft nodes before linearized reading' (duration: 156.948454ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:16:47.085647Z","caller":"traceutil/trace.go:171","msg":"trace[1794501542] linearizableReadLoop","detail":"{readStateIndex:14807; appliedIndex:14806; }","duration":"119.727065ms","start":"2026-09-08T16:16:46.965876Z","end":"2026-09-08T16:16:47.085603Z","steps":["trace[1794501542] 'read index received' (duration: 117.217626ms)","trace[1794501542] 'applied index is now lower than readState.Index' (duration: 2.508239ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:16:47.085919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.327043ms","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-09-08T16:16:47.085995Z","caller":"traceutil/trace.go:171","msg":"trace[1095080417] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14313; }","duration":"160.444876ms","start":"2026-09-08T16:16:46.925526Z","end":"2026-09-08T16:16:47.085971Z","steps":["trace[1095080417] 'agreement among raft nodes before linearized reading' (duration: 160.22154ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:16:47.086351Z","caller":"traceutil/trace.go:171","msg":"trace[1722499914] transaction","detail":"{read_only:false; response_revision:14313; number_of_response:1; }","duration":"218.100662ms","start":"2026-09-08T16:16:46.867596Z","end":"2026-09-08T16:16:47.085697Z","steps":["trace[1722499914] 'process raft request' (duration: 215.4607ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:16:47.125712Z","caller":"traceutil/trace.go:171","msg":"trace[308025473] transaction","detail":"{read_only:false; response_revision:14314; number_of_response:1; }","duration":"149.227955ms","start":"2026-09-08T16:16:46.976447Z","end":"2026-09-08T16:16:47.125675Z","steps":["trace[308025473] 'process raft request' (duration: 148.958039ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:16:47.125875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.09654ms","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-09-08T16:16:47.125942Z","caller":"traceutil/trace.go:171","msg":"trace[1170025273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14314; }","duration":"119.228373ms","start":"2026-09-08T16:16:47.006696Z","end":"2026-09-08T16:16:47.125924Z","steps":["trace[1170025273] 'agreement among raft nodes before linearized reading' (duration: 118.913766ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:16:48.962792Z","caller":"traceutil/trace.go:171","msg":"trace[742913555] transaction","detail":"{read_only:false; response_revision:14323; number_of_response:1; }","duration":"109.744281ms","start":"2026-09-08T16:16:48.853004Z","end":"2026-09-08T16:16:48.962748Z","steps":["trace[742913555] 'process raft request' (duration: 35.780575ms)","trace[742913555] 'compare' (duration: 73.779552ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:12.61627Z","caller":"traceutil/trace.go:171","msg":"trace[1361478028] transaction","detail":"{read_only:false; response_revision:14638; number_of_response:1; }","duration":"371.72797ms","start":"2026-09-08T16:17:12.244491Z","end":"2026-09-08T16:17:12.616219Z","steps":["trace[1361478028] 'process raft request' (duration: 371.473063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:12.616511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:12.24445Z","time spent":"371.918554ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:17:12.618059Z","caller":"traceutil/trace.go:171","msg":"trace[1910729379] transaction","detail":"{read_only:false; response_revision:14639; number_of_response:1; }","duration":"333.831015ms","start":"2026-09-08T16:17:12.284163Z","end":"2026-09-08T16:17:12.617994Z","steps":["trace[1910729379] 'process raft request' (duration: 333.166479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:12.619131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:12.284134Z","time spent":"334.082171ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:17:12.632237Z","caller":"traceutil/trace.go:171","msg":"trace[1271382746] linearizableReadLoop","detail":"{readStateIndex:15147; appliedIndex:15145; }","duration":"258.925826ms","start":"2026-09-08T16:17:12.373278Z","end":"2026-09-08T16:17:12.632203Z","steps":["trace[1271382746] 'read index received' (duration: 242.717187ms)","trace[1271382746] 'applied index is now lower than readState.Index' (duration: 16.207049ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:17:12.632748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.433657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-p5dnj\" ","response":"range_response_count:1 size:20652"} {"level":"info","ts":"2026-09-08T16:17:12.632885Z","caller":"traceutil/trace.go:171","msg":"trace[481766460] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-p5dnj; range_end:; response_count:1; response_revision:14639; }","duration":"259.633812ms","start":"2026-09-08T16:17:12.373211Z","end":"2026-09-08T16:17:12.632845Z","steps":["trace[481766460] 'agreement among raft nodes before linearized reading' (duration: 259.198242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:12.632778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.519684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T16:17:12.632961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.942847ms","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-09-08T16:17:12.633134Z","caller":"traceutil/trace.go:171","msg":"trace[370651548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14639; }","duration":"113.209813ms","start":"2026-09-08T16:17:12.519887Z","end":"2026-09-08T16:17:12.633097Z","steps":["trace[370651548] 'agreement among raft nodes before linearized reading' (duration: 112.780382ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:12.633033Z","caller":"traceutil/trace.go:171","msg":"trace[1774729547] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:14639; }","duration":"177.820312ms","start":"2026-09-08T16:17:12.455187Z","end":"2026-09-08T16:17:12.633008Z","steps":["trace[1774729547] 'agreement among raft nodes before linearized reading' (duration: 177.475363ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:53.323366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12761} {"level":"info","ts":"2026-09-08T16:17:53.409152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12761,"took":"82.484427ms","hash":2247933624,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":30248960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:17:53.40924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2247933624,"revision":12761,"compact-revision":10414} {"level":"info","ts":"2026-09-08T16:18:45.832519Z","caller":"traceutil/trace.go:171","msg":"trace[1160264288] linearizableReadLoop","detail":"{readStateIndex:16022; appliedIndex:16021; }","duration":"113.131927ms","start":"2026-09-08T16:18:45.719327Z","end":"2026-09-08T16:18:45.832459Z","steps":["trace[1160264288] 'read index received' (duration: 112.986124ms)","trace[1160264288] 'applied index is now lower than readState.Index' (duration: 144.483µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:18:45.832722Z","caller":"traceutil/trace.go:171","msg":"trace[1001080942] transaction","detail":"{read_only:false; response_revision:15488; number_of_response:1; }","duration":"233.977574ms","start":"2026-09-08T16:18:45.598728Z","end":"2026-09-08T16:18:45.832705Z","steps":["trace[1001080942] 'process raft request' (duration: 233.547894ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:45.832984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.291231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:18:45.833086Z","caller":"traceutil/trace.go:171","msg":"trace[117449412] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15488; }","duration":"113.434305ms","start":"2026-09-08T16:18:45.719631Z","end":"2026-09-08T16:18:45.833066Z","steps":["trace[117449412] 'agreement among raft nodes before linearized reading' (duration: 113.267981ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:45.833255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.922207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:18:45.833327Z","caller":"traceutil/trace.go:171","msg":"trace[1592216572] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15488; }","duration":"114.037809ms","start":"2026-09-08T16:18:45.719269Z","end":"2026-09-08T16:18:45.833307Z","steps":["trace[1592216572] 'agreement among raft nodes before linearized reading' (duration: 113.916667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:45.833378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.630678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-08T16:18:45.833403Z","caller":"traceutil/trace.go:171","msg":"trace[514937167] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15488; }","duration":"109.681358ms","start":"2026-09-08T16:18:45.723714Z","end":"2026-09-08T16:18:45.833396Z","steps":["trace[514937167] 'agreement among raft nodes before linearized reading' (duration: 109.559936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:45.833629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.748442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-09-08T16:18:45.833699Z","caller":"traceutil/trace.go:171","msg":"trace[664490262] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15488; }","duration":"113.855704ms","start":"2026-09-08T16:18:45.719826Z","end":"2026-09-08T16:18:45.833682Z","steps":["trace[664490262] 'agreement among raft nodes before linearized reading' (duration: 113.67815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:18:45.83372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.804572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-09-08T16:18:45.833745Z","caller":"traceutil/trace.go:171","msg":"trace[1208815753] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15488; }","duration":"113.852994ms","start":"2026-09-08T16:18:45.719886Z","end":"2026-09-08T16:18:45.833739Z","steps":["trace[1208815753] 'agreement among raft nodes before linearized reading' (duration: 113.779342ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:21:48.434672Z","caller":"traceutil/trace.go:171","msg":"trace[596807759] transaction","detail":"{read_only:false; response_revision:16961; number_of_response:1; }","duration":"129.041866ms","start":"2026-09-08T16:21:48.305597Z","end":"2026-09-08T16:21:48.434638Z","steps":["trace[596807759] 'process raft request' (duration: 128.675308ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:21:48.434672Z","caller":"traceutil/trace.go:171","msg":"trace[1494512953] transaction","detail":"{read_only:false; response_revision:16960; number_of_response:1; }","duration":"143.614662ms","start":"2026-09-08T16:21:48.291016Z","end":"2026-09-08T16:21:48.43463Z","steps":["trace[1494512953] 'process raft request' (duration: 44.97249ms)","trace[1494512953] 'compare' (duration: 97.751012ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:21:48.434764Z","caller":"traceutil/trace.go:171","msg":"trace[27542887] linearizableReadLoop","detail":"{readStateIndex:17541; appliedIndex:17540; }","duration":"141.117145ms","start":"2026-09-08T16:21:48.293616Z","end":"2026-09-08T16:21:48.434733Z","steps":["trace[27542887] 'read index received' (duration: 42.388041ms)","trace[27542887] 'applied index is now lower than readState.Index' (duration: 98.727244ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:21:48.435462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.042303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-09-08T16:21:48.435729Z","caller":"traceutil/trace.go:171","msg":"trace[865303326] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16961; }","duration":"106.358001ms","start":"2026-09-08T16:21:48.329345Z","end":"2026-09-08T16:21:48.435703Z","steps":["trace[865303326] 'agreement among raft nodes before linearized reading' (duration: 106.004723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:21:48.436326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.704151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:897522"} {"level":"info","ts":"2026-09-08T16:21:48.436406Z","caller":"traceutil/trace.go:171","msg":"trace[2055929337] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:16961; }","duration":"142.828964ms","start":"2026-09-08T16:21:48.29356Z","end":"2026-09-08T16:21:48.436389Z","steps":["trace[2055929337] 'agreement among raft nodes before linearized reading' (duration: 141.604267ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:22:53.331918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15144} {"level":"info","ts":"2026-09-08T16:22:53.43774Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15144,"took":"102.115533ms","hash":948211800,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":31162368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-08T16:22:53.437815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":948211800,"revision":15144,"compact-revision":12761} {"level":"warn","ts":"2026-09-08T16:23:50.127902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.553736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:23:50.128165Z","caller":"traceutil/trace.go:171","msg":"trace[2026964158] transaction","detail":"{read_only:false; response_revision:17732; number_of_response:1; }","duration":"149.310014ms","start":"2026-09-08T16:23:49.978819Z","end":"2026-09-08T16:23:50.128129Z","steps":["trace[2026964158] 'process raft request' (duration: 10.422561ms)","trace[2026964158] 'compare' (duration: 138.385502ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:24:44.976167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.308875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-08T16:24:44.976264Z","caller":"traceutil/trace.go:171","msg":"trace[1338972977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18126; }","duration":"117.415308ms","start":"2026-09-08T16:24:44.858829Z","end":"2026-09-08T16:24:44.976245Z","steps":["trace[1338972977] 'range keys from in-memory index tree' (duration: 117.09016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:45.450619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.778544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:24:45.45072Z","caller":"traceutil/trace.go:171","msg":"trace[1378619340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18127; }","duration":"314.983758ms","start":"2026-09-08T16:24:45.135716Z","end":"2026-09-08T16:24:45.4507Z","steps":["trace[1378619340] 'range keys from in-memory index tree' (duration: 314.649951ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:45.450763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:45.135685Z","time spent":"315.06636ms","remote":"127.0.0.1:37080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T16:24:45.451261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.719913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-p5dnj\" ","response":"range_response_count:1 size:20755"} {"level":"info","ts":"2026-09-08T16:24:45.451313Z","caller":"traceutil/trace.go:171","msg":"trace[602045922] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-p5dnj; range_end:; response_count:1; response_revision:18127; }","duration":"238.851946ms","start":"2026-09-08T16:24:45.212449Z","end":"2026-09-08T16:24:45.451301Z","steps":["trace[602045922] 'range keys from in-memory index tree' (duration: 238.464037ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:45.454568Z","caller":"traceutil/trace.go:171","msg":"trace[1510725673] transaction","detail":"{read_only:false; response_revision:18128; number_of_response:1; }","duration":"180.702782ms","start":"2026-09-08T16:24:45.273846Z","end":"2026-09-08T16:24:45.454549Z","steps":["trace[1510725673] 'process raft request' (duration: 180.16793ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:45.883965Z","caller":"traceutil/trace.go:171","msg":"trace[126703468] transaction","detail":"{read_only:false; response_revision:18130; number_of_response:1; }","duration":"337.015999ms","start":"2026-09-08T16:24:45.546929Z","end":"2026-09-08T16:24:45.883945Z","steps":["trace[126703468] 'process raft request' (duration: 336.942767ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:45.884002Z","caller":"traceutil/trace.go:171","msg":"trace[129775576] linearizableReadLoop","detail":"{readStateIndex:18751; appliedIndex:18750; }","duration":"339.699709ms","start":"2026-09-08T16:24:45.544273Z","end":"2026-09-08T16:24:45.883973Z","steps":["trace[129775576] 'read index received' (duration: 319.032531ms)","trace[129775576] 'applied index is now lower than readState.Index' (duration: 20.664718ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:24:45.884017Z","caller":"traceutil/trace.go:171","msg":"trace[835806141] transaction","detail":"{read_only:false; response_revision:18129; number_of_response:1; }","duration":"403.916026ms","start":"2026-09-08T16:24:45.480074Z","end":"2026-09-08T16:24:45.88399Z","steps":["trace[835806141] 'process raft request' (duration: 383.244348ms)","trace[835806141] 'compare' (duration: 19.767457ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:24:45.884127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:45.546889Z","time spent":"337.154652ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:24:45.884131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:45.480045Z","time spent":"404.021229ms","remote":"127.0.0.1:37292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20870,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:24:45.884331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.035643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zcq5g\" ","response":"range_response_count:1 size:22125"} {"level":"warn","ts":"2026-09-08T16:24:45.88427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.956845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-08T16:24:45.884374Z","caller":"traceutil/trace.go:171","msg":"trace[1573850029] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zcq5g; range_end:; response_count:1; response_revision:18130; }","duration":"138.129415ms","start":"2026-09-08T16:24:45.746233Z","end":"2026-09-08T16:24:45.884362Z","steps":["trace[1573850029] 'agreement among raft nodes before linearized reading' (duration: 138.013463ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:45.884438Z","caller":"traceutil/trace.go:171","msg":"trace[214423908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18130; }","duration":"340.15813ms","start":"2026-09-08T16:24:45.544216Z","end":"2026-09-08T16:24:45.884374Z","steps":["trace[214423908] 'agreement among raft nodes before linearized reading' (duration: 339.881184ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:45.884569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:45.544187Z","time spent":"340.364616ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-08T16:24:45.88461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.774957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-08T16:24:45.884667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.461988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-08T16:24:45.884716Z","caller":"traceutil/trace.go:171","msg":"trace[1590806322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18130; }","duration":"111.904629ms","start":"2026-09-08T16:24:45.772796Z","end":"2026-09-08T16:24:45.8847Z","steps":["trace[1590806322] 'agreement among raft nodes before linearized reading' (duration: 111.735945ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:45.884741Z","caller":"traceutil/trace.go:171","msg":"trace[927382333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18130; }","duration":"114.595151ms","start":"2026-09-08T16:24:45.770124Z","end":"2026-09-08T16:24:45.884719Z","steps":["trace[927382333] 'agreement among raft nodes before linearized reading' (duration: 114.407057ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:54.507555Z","caller":"traceutil/trace.go:171","msg":"trace[1094403229] linearizableReadLoop","detail":"{readStateIndex:18920; appliedIndex:18918; }","duration":"127.436409ms","start":"2026-09-08T16:24:54.380088Z","end":"2026-09-08T16:24:54.507525Z","steps":["trace[1094403229] 'read index received' (duration: 2.797015ms)","trace[1094403229] 'applied index is now lower than readState.Index' (duration: 124.637814ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:24:54.507598Z","caller":"traceutil/trace.go:171","msg":"trace[746777799] transaction","detail":"{read_only:false; response_revision:18293; number_of_response:1; }","duration":"127.774577ms","start":"2026-09-08T16:24:54.379781Z","end":"2026-09-08T16:24:54.507556Z","steps":["trace[746777799] 'process raft request' (duration: 96.443232ms)","trace[746777799] 'compare' (duration: 31.152101ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:24:54.507888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.759016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-08T16:24:54.50802Z","caller":"traceutil/trace.go:171","msg":"trace[1581999606] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:18293; }","duration":"127.942911ms","start":"2026-09-08T16:24:54.380044Z","end":"2026-09-08T16:24:54.507987Z","steps":["trace[1581999606] 'agreement among raft nodes before linearized reading' (duration: 127.591483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:54.512801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.211099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-09-08T16:24:54.513198Z","caller":"traceutil/trace.go:171","msg":"trace[2086620348] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:18293; }","duration":"132.367843ms","start":"2026-09-08T16:24:54.380531Z","end":"2026-09-08T16:24:54.512899Z","steps":["trace[2086620348] 'agreement among raft nodes before linearized reading' (duration: 132.055066ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:54.513738Z","caller":"traceutil/trace.go:171","msg":"trace[1477733823] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18293; }","duration":"123.348524ms","start":"2026-09-08T16:24:54.390369Z","end":"2026-09-08T16:24:54.513717Z","steps":["trace[1477733823] 'process raft request' (duration: 122.123216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:54.514409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.56667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112152"} {"level":"info","ts":"2026-09-08T16:24:54.51449Z","caller":"traceutil/trace.go:171","msg":"trace[965901798] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:18293; }","duration":"133.646702ms","start":"2026-09-08T16:24:54.380808Z","end":"2026-09-08T16:24:54.514455Z","steps":["trace[965901798] 'agreement among raft nodes before linearized reading' (duration: 133.266133ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:26:59.313939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"caafea9f270da559","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:26:59.320386Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-08T16:26:59.320586Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-08T16:27:53.340558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17382} {"level":"info","ts":"2026-09-08T16:27:53.448087Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17382,"took":"103.987556ms","hash":3431891887,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":34082816,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T16:27:53.448163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3431891887,"revision":17382,"compact-revision":15144} {"level":"warn","ts":"2026-09-08T16:30:40.431234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.365782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-08T16:30:40.431338Z","caller":"traceutil/trace.go:171","msg":"trace[1018767598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20845; }","duration":"268.523356ms","start":"2026-09-08T16:30:40.16279Z","end":"2026-09-08T16:30:40.431314Z","steps":["trace[1018767598] 'range keys from in-memory index tree' (duration: 268.154668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:40.431243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.216229ms","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-09-08T16:30:40.431434Z","caller":"traceutil/trace.go:171","msg":"trace[1844107704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20845; }","duration":"268.462195ms","start":"2026-09-08T16:30:40.162948Z","end":"2026-09-08T16:30:40.43141Z","steps":["trace[1844107704] 'range keys from in-memory index tree' (duration: 268.071816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:40.431875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.774368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:30:40.432197Z","caller":"traceutil/trace.go:171","msg":"trace[1365125917] transaction","detail":"{read_only:false; response_revision:20846; number_of_response:1; }","duration":"182.987396ms","start":"2026-09-08T16:30:40.249106Z","end":"2026-09-08T16:30:40.432093Z","steps":["trace[1365125917] 'process raft request' (duration: 38.744027ms)","trace[1365125917] 'compare' (duration: 143.177524ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:40.68906Z","caller":"traceutil/trace.go:171","msg":"trace[1286012540] transaction","detail":"{read_only:false; response_revision:20847; number_of_response:1; }","duration":"251.83157ms","start":"2026-09-08T16:30:40.437199Z","end":"2026-09-08T16:30:40.689031Z","steps":["trace[1286012540] 'process raft request' (duration: 189.142548ms)","trace[1286012540] 'compare' (duration: 62.560669ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:40.705239Z","caller":"traceutil/trace.go:171","msg":"trace[1697433173] linearizableReadLoop","detail":"{readStateIndex:21562; appliedIndex:21559; }","duration":"197.478202ms","start":"2026-09-08T16:30:40.507727Z","end":"2026-09-08T16:30:40.705205Z","steps":["trace[1697433173] 'read index received' (duration: 118.631246ms)","trace[1697433173] 'applied index is now lower than readState.Index' (duration: 78.845846ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:40.705315Z","caller":"traceutil/trace.go:171","msg":"trace[1201099940] transaction","detail":"{read_only:false; response_revision:20849; number_of_response:1; }","duration":"267.562153ms","start":"2026-09-08T16:30:40.437719Z","end":"2026-09-08T16:30:40.705281Z","steps":["trace[1201099940] 'process raft request' (duration: 267.42944ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:40.705299Z","caller":"traceutil/trace.go:171","msg":"trace[1514057104] transaction","detail":"{read_only:false; response_revision:20848; number_of_response:1; }","duration":"267.576204ms","start":"2026-09-08T16:30:40.437671Z","end":"2026-09-08T16:30:40.705247Z","steps":["trace[1514057104] 'process raft request' (duration: 267.300277ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:40.705591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.842769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-09-08T16:30:40.705621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.283593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-08T16:30:40.705726Z","caller":"traceutil/trace.go:171","msg":"trace[263510325] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20849; }","duration":"132.457987ms","start":"2026-09-08T16:30:40.573247Z","end":"2026-09-08T16:30:40.705705Z","steps":["trace[263510325] 'agreement among raft nodes before linearized reading' (duration: 132.227232ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:40.705654Z","caller":"traceutil/trace.go:171","msg":"trace[241317332] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20849; }","duration":"197.984263ms","start":"2026-09-08T16:30:40.507648Z","end":"2026-09-08T16:30:40.705632Z","steps":["trace[241317332] 'agreement among raft nodes before linearized reading' (duration: 197.722007ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:41.017383Z","caller":"traceutil/trace.go:171","msg":"trace[519923588] linearizableReadLoop","detail":"{readStateIndex:21564; appliedIndex:21562; }","duration":"256.668512ms","start":"2026-09-08T16:30:40.760679Z","end":"2026-09-08T16:30:41.017347Z","steps":["trace[519923588] 'read index received' (duration: 256.148699ms)","trace[519923588] 'applied index is now lower than readState.Index' (duration: 518.813µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:41.01753Z","caller":"traceutil/trace.go:171","msg":"trace[53695470] transaction","detail":"{read_only:false; response_revision:20850; number_of_response:1; }","duration":"306.576927ms","start":"2026-09-08T16:30:40.710897Z","end":"2026-09-08T16:30:41.017474Z","steps":["trace[53695470] 'process raft request' (duration: 306.066805ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:41.017591Z","caller":"traceutil/trace.go:171","msg":"trace[1322144262] transaction","detail":"{read_only:false; response_revision:20851; number_of_response:1; }","duration":"304.682513ms","start":"2026-09-08T16:30:40.712858Z","end":"2026-09-08T16:30:41.017541Z","steps":["trace[1322144262] 'process raft request' (duration: 304.419678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:41.017628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.903818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:30:41.01769Z","caller":"traceutil/trace.go:171","msg":"trace[861834157] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:20851; }","duration":"257.065831ms","start":"2026-09-08T16:30:40.760602Z","end":"2026-09-08T16:30:41.017668Z","steps":["trace[861834157] 'agreement among raft nodes before linearized reading' (duration: 256.921548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:41.01778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:40.710839Z","time spent":"306.800942ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:30:41.017784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:40.712818Z","time spent":"304.855837ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:30:41.372885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.836696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:30:41.373028Z","caller":"traceutil/trace.go:171","msg":"trace[1848202195] linearizableReadLoop","detail":"{readStateIndex:21565; appliedIndex:21564; }","duration":"243.493657ms","start":"2026-09-08T16:30:41.129511Z","end":"2026-09-08T16:30:41.373005Z","steps":["trace[1848202195] 'read index received' (duration: 59.380644ms)","trace[1848202195] 'applied index is now lower than readState.Index' (duration: 184.111323ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:41.373066Z","caller":"traceutil/trace.go:171","msg":"trace[1689711779] transaction","detail":"{read_only:false; response_revision:20852; number_of_response:1; }","duration":"290.27456ms","start":"2026-09-08T16:30:41.082748Z","end":"2026-09-08T16:30:41.373023Z","steps":["trace[1689711779] 'process raft request' (duration: 106.156538ms)","trace[1689711779] 'compare' (duration: 183.408255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:41.373214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.66521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-08T16:30:41.373267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.526675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-08T16:30:41.373363Z","caller":"traceutil/trace.go:171","msg":"trace[330393931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20852; }","duration":"128.683369ms","start":"2026-09-08T16:30:41.244654Z","end":"2026-09-08T16:30:41.373337Z","steps":["trace[330393931] 'agreement among raft nodes before linearized reading' (duration: 128.496285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:41.373515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.60809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:30:41.373528Z","caller":"traceutil/trace.go:171","msg":"trace[447370470] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:20852; }","duration":"244.054729ms","start":"2026-09-08T16:30:41.129443Z","end":"2026-09-08T16:30:41.373498Z","steps":["trace[447370470] 'agreement among raft nodes before linearized reading' (duration: 243.612619ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:41.373557Z","caller":"traceutil/trace.go:171","msg":"trace[125719761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20852; }","duration":"237.948838ms","start":"2026-09-08T16:30:41.135596Z","end":"2026-09-08T16:30:41.373545Z","steps":["trace[125719761] 'agreement among raft nodes before linearized reading' (duration: 237.533399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:41.679229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.901546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:30:41.679347Z","caller":"traceutil/trace.go:171","msg":"trace[352170424] transaction","detail":"{read_only:false; response_revision:20853; number_of_response:1; }","duration":"299.656437ms","start":"2026-09-08T16:30:41.37967Z","end":"2026-09-08T16:30:41.679326Z","steps":["trace[352170424] 'process raft request' (duration: 125.541507ms)","trace[352170424] 'compare' (duration: 173.69339ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:41.710675Z","caller":"traceutil/trace.go:171","msg":"trace[217099843] transaction","detail":"{read_only:false; response_revision:20854; number_of_response:1; }","duration":"319.388243ms","start":"2026-09-08T16:30:41.391231Z","end":"2026-09-08T16:30:41.71062Z","steps":["trace[217099843] 'process raft request' (duration: 319.054375ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:41.710897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:41.391197Z","time spent":"319.570367ms","remote":"127.0.0.1:37398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T16:30:41.710847Z","caller":"traceutil/trace.go:171","msg":"trace[1319350882] transaction","detail":"{read_only:false; response_revision:20855; number_of_response:1; }","duration":"290.760441ms","start":"2026-09-08T16:30:41.420038Z","end":"2026-09-08T16:30:41.710798Z","steps":["trace[1319350882] 'process raft request' (duration: 290.503285ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:42.222253Z","caller":"traceutil/trace.go:171","msg":"trace[1022357762] transaction","detail":"{read_only:false; response_revision:20856; number_of_response:1; }","duration":"152.971021ms","start":"2026-09-08T16:30:42.069215Z","end":"2026-09-08T16:30:42.222186Z","steps":["trace[1022357762] 'process raft request' (duration: 152.696165ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:32:53.346641Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19682} {"level":"info","ts":"2026-09-08T16:32:53.465744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19682,"took":"115.671017ms","hash":200589112,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":29392896,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T16:32:53.465893Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":200589112,"revision":19682,"compact-revision":17382} {"level":"info","ts":"2026-09-08T16:34:50.842182Z","caller":"traceutil/trace.go:171","msg":"trace[1542433981] transaction","detail":"{read_only:false; response_revision:23384; number_of_response:1; }","duration":"138.530966ms","start":"2026-09-08T16:34:50.703624Z","end":"2026-09-08T16:34:50.842154Z","steps":["trace[1542433981] 'process raft request' (duration: 138.339992ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:34:50.872276Z","caller":"traceutil/trace.go:171","msg":"trace[279219321] transaction","detail":"{read_only:false; response_revision:23385; number_of_response:1; }","duration":"115.32808ms","start":"2026-09-08T16:34:50.756916Z","end":"2026-09-08T16:34:50.872244Z","steps":["trace[279219321] 'process raft request' (duration: 115.104935ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:34:51.278238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.175861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:34:51.278388Z","caller":"traceutil/trace.go:171","msg":"trace[1388568769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23385; }","duration":"142.394066ms","start":"2026-09-08T16:34:51.135963Z","end":"2026-09-08T16:34:51.278357Z","steps":["trace[1388568769] 'range keys from in-memory index tree' (duration: 142.058959ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:34:51.278474Z","caller":"traceutil/trace.go:171","msg":"trace[748161252] transaction","detail":"{read_only:false; response_revision:23386; number_of_response:1; }","duration":"109.156386ms","start":"2026-09-08T16:34:51.16926Z","end":"2026-09-08T16:34:51.278416Z","steps":["trace[748161252] 'process raft request' (duration: 100.387514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:34:51.27858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.266574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-08T16:34:51.278671Z","caller":"traceutil/trace.go:171","msg":"trace[1928779537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23385; }","duration":"247.423938ms","start":"2026-09-08T16:34:51.031229Z","end":"2026-09-08T16:34:51.278653Z","steps":["trace[1928779537] 'range keys from in-memory index tree' (duration: 246.627748ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:37:53.355703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21906} {"level":"info","ts":"2026-09-08T16:37:53.475395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21906,"took":"115.517982ms","hash":313277676,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":42856448,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-08T16:37:53.475492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":313277676,"revision":21906,"compact-revision":19682} {"level":"info","ts":"2026-09-08T16:42:53.361737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25052} {"level":"info","ts":"2026-09-08T16:42:53.514533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25052,"took":"149.030108ms","hash":1490323994,"current-db-size-bytes":55980032,"current-db-size":"56 MB","current-db-size-in-use-bytes":29831168,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:42:53.514633Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1490323994,"revision":25052,"compact-revision":21906} {"level":"info","ts":"2026-09-08T16:44:29.652589Z","caller":"traceutil/trace.go:171","msg":"trace[1294387952] transaction","detail":"{read_only:false; response_revision:27974; number_of_response:1; }","duration":"102.437317ms","start":"2026-09-08T16:44:29.550127Z","end":"2026-09-08T16:44:29.652565Z","steps":["trace[1294387952] 'process raft request' (duration: 101.939296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:44:29.652801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.54169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/magnum-api\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-08T16:44:29.652912Z","caller":"traceutil/trace.go:171","msg":"trace[314368918] range","detail":"{range_begin:/registry/serviceaccounts/openstack/magnum-api; range_end:; response_count:1; response_revision:27974; }","duration":"102.692532ms","start":"2026-09-08T16:44:29.550194Z","end":"2026-09-08T16:44:29.652887Z","steps":["trace[314368918] 'agreement among raft nodes before linearized reading' (duration: 102.419006ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:44:31.912151Z","caller":"traceutil/trace.go:171","msg":"trace[354984691] transaction","detail":"{read_only:false; response_revision:27991; number_of_response:1; }","duration":"101.791442ms","start":"2026-09-08T16:44:31.810332Z","end":"2026-09-08T16:44:31.912124Z","steps":["trace[354984691] 'process raft request' (duration: 96.118371ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:44:33.468395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.305944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388885"} {"level":"info","ts":"2026-09-08T16:44:33.468475Z","caller":"traceutil/trace.go:171","msg":"trace[1362823516] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:27999; }","duration":"121.431947ms","start":"2026-09-08T16:44:33.347023Z","end":"2026-09-08T16:44:33.468455Z","steps":["trace[1362823516] 'range keys from in-memory index tree' (duration: 118.069169ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:44:33.469834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.287254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388885"} {"level":"info","ts":"2026-09-08T16:44:33.469938Z","caller":"traceutil/trace.go:171","msg":"trace[1255084011] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:27999; }","duration":"118.403747ms","start":"2026-09-08T16:44:33.351503Z","end":"2026-09-08T16:44:33.469907Z","steps":["trace[1255084011] 'range keys from in-memory index tree' (duration: 113.80643ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:44:36.138334Z","caller":"traceutil/trace.go:171","msg":"trace[1481214709] transaction","detail":"{read_only:false; response_revision:28021; number_of_response:1; }","duration":"101.994136ms","start":"2026-09-08T16:44:36.036295Z","end":"2026-09-08T16:44:36.138289Z","steps":["trace[1481214709] 'process raft request' (duration: 66.898942ms)","trace[1481214709] 'compare' (duration: 34.880099ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:44:36.140208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.543486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" limit:500 ","response":"range_response_count:176 size:1944316"} {"level":"info","ts":"2026-09-08T16:44:36.140295Z","caller":"traceutil/trace.go:171","msg":"trace[427466466] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:176; response_revision:28021; }","duration":"101.651019ms","start":"2026-09-08T16:44:36.038626Z","end":"2026-09-08T16:44:36.140277Z","steps":["trace[427466466] 'agreement among raft nodes before linearized reading' (duration: 99.652992ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:44:36.140378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.25972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" limit:500 ","response":"range_response_count:176 size:1944316"} {"level":"info","ts":"2026-09-08T16:44:36.140462Z","caller":"traceutil/trace.go:171","msg":"trace[817632840] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:176; response_revision:28021; }","duration":"101.378753ms","start":"2026-09-08T16:44:36.039065Z","end":"2026-09-08T16:44:36.140444Z","steps":["trace[817632840] 'agreement among raft nodes before linearized reading' (duration: 99.312654ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:45:00.947427Z","caller":"traceutil/trace.go:171","msg":"trace[996426665] transaction","detail":"{read_only:false; response_revision:28210; number_of_response:1; }","duration":"177.458566ms","start":"2026-09-08T16:45:00.769918Z","end":"2026-09-08T16:45:00.947376Z","steps":["trace[996426665] 'process raft request' (duration: 177.285532ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:45:00.971109Z","caller":"traceutil/trace.go:171","msg":"trace[1844898024] linearizableReadLoop","detail":"{readStateIndex:29139; appliedIndex:29137; }","duration":"106.680986ms","start":"2026-09-08T16:45:00.864406Z","end":"2026-09-08T16:45:00.971087Z","steps":["trace[1844898024] 'read index received' (duration: 82.808722ms)","trace[1844898024] 'applied index is now lower than readState.Index' (duration: 23.871104ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:45:00.971234Z","caller":"traceutil/trace.go:171","msg":"trace[1182764244] transaction","detail":"{read_only:false; response_revision:28211; number_of_response:1; }","duration":"176.937035ms","start":"2026-09-08T16:45:00.794267Z","end":"2026-09-08T16:45:00.971204Z","steps":["trace[1182764244] 'process raft request' (duration: 176.682489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:45:00.97135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.930301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-09-08T16:45:00.971389Z","caller":"traceutil/trace.go:171","msg":"trace[1226851160] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:28211; }","duration":"107.025033ms","start":"2026-09-08T16:45:00.864355Z","end":"2026-09-08T16:45:00.97138Z","steps":["trace[1226851160] 'agreement among raft nodes before linearized reading' (duration: 106.86532ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:45:00.97152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.630811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-mapping-config\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-09-08T16:45:00.971587Z","caller":"traceutil/trace.go:171","msg":"trace[606413316] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-mapping-config; range_end:; response_count:1; response_revision:28211; }","duration":"102.724095ms","start":"2026-09-08T16:45:00.868851Z","end":"2026-09-08T16:45:00.971575Z","steps":["trace[606413316] 'agreement among raft nodes before linearized reading' (duration: 102.527899ms)"],"step_count":1}