{"level":"warn","ts":"2026-06-03T07:23:42.421908Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T07:23:42.422065Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.185:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.185:2380","--initial-cluster=instance=https://199.19.213.185:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.185:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.185: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-06-03T07:23:42.422151Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-03T07:23:42.422165Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-06-03T07:23:42.422188Z","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-06-03T07:23:42.42307Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"]} {"level":"info","ts":"2026-06-03T07:23:42.423227Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.185: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-06-03T07:23:42.429056Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.545203ms"} {"level":"info","ts":"2026-06-03T07:23:42.440999Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dcbda34f8b503a3","cluster-id":"2e806bc52dde46af"} {"level":"info","ts":"2026-06-03T07:23:42.441108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=()"} {"level":"info","ts":"2026-06-03T07:23:42.441134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 0"} {"level":"info","ts":"2026-06-03T07:23:42.441152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dcbda34f8b503a3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-03T07:23:42.441172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 1"} {"level":"info","ts":"2026-06-03T07:23:42.441218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"warn","ts":"2026-06-03T07:23:42.450812Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-03T07:23:42.453114Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-03T07:23:42.454825Z","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-06-03T07:23:42.457528Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dcbda34f8b503a3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-03T07:23:42.46217Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dcbda34f8b503a3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-03T07:23:42.462288Z","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-06-03T07:23:42.462552Z","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-06-03T07:23:42.462572Z","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-06-03T07:23:42.462827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"info","ts":"2026-06-03T07:23:42.462838Z","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-06-03T07:23:42.46292Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","added-peer-id":"dcbda34f8b503a3","added-peer-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-06-03T07:23:42.462969Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-06-03T07:23:42.462998Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-06-03T07:23:42.463112Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dcbda34f8b503a3","initial-advertise-peer-urls":["https://199.19.213.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-03T07:23:42.463147Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-03T07:23:42.941876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 is starting a new election at term 1"} {"level":"info","ts":"2026-06-03T07:23:42.941944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-03T07:23:42.941981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgPreVoteResp from dcbda34f8b503a3 at term 1"} {"level":"info","ts":"2026-06-03T07:23:42.941995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became candidate at term 2"} {"level":"info","ts":"2026-06-03T07:23:42.942002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgVoteResp from dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-06-03T07:23:42.942015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became leader at term 2"} {"level":"info","ts":"2026-06-03T07:23:42.942025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dcbda34f8b503a3 elected leader dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-06-03T07:23:42.94314Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:23:42.943851Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dcbda34f8b503a3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.185:2379]}","request-path":"/0/members/dcbda34f8b503a3/attributes","cluster-id":"2e806bc52dde46af","publish-timeout":"7s"} {"level":"info","ts":"2026-06-03T07:23:42.943868Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T07:23:42.944067Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-03T07:23:42.944261Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-03T07:23:42.944323Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-03T07:23:42.944289Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:23:42.944513Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:23:42.944585Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-03T07:23:42.949191Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-03T07:23:42.950692Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.185:2379"} {"level":"info","ts":"2026-06-03T07:24:02.01028Z","caller":"traceutil/trace.go:171","msg":"trace[402416024] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"139.724916ms","start":"2026-06-03T07:24:01.870529Z","end":"2026-06-03T07:24:02.010254Z","steps":["trace[402416024] 'process raft request' (duration: 139.486677ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:03.742833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328115705s","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-06-03T07:24:03.742913Z","caller":"traceutil/trace.go:171","msg":"trace[947379593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:378; }","duration":"1.328258316s","start":"2026-06-03T07:24:02.414637Z","end":"2026-06-03T07:24:03.742896Z","steps":["trace[947379593] 'range keys from in-memory index tree' (duration: 1.328029928s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:03.742964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:02.414617Z","time spent":"1.328338142s","remote":"127.0.0.1:51294","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-06-03T07:24:03.743117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231874318s","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-06-03T07:24:03.743195Z","caller":"traceutil/trace.go:171","msg":"trace[504051614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:378; }","duration":"1.231994896s","start":"2026-06-03T07:24:02.511181Z","end":"2026-06-03T07:24:03.743176Z","steps":["trace[504051614] 'range keys from in-memory index tree' (duration: 1.23177836s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:03.743228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.004935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:24:03.743234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:02.511161Z","time spent":"1.232065272s","remote":"127.0.0.1:51294","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":"info","ts":"2026-06-03T07:24:03.743262Z","caller":"traceutil/trace.go:171","msg":"trace[1296942352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:378; }","duration":"806.05776ms","start":"2026-06-03T07:24:02.937192Z","end":"2026-06-03T07:24:03.743249Z","steps":["trace[1296942352] 'range keys from in-memory index tree' (duration: 805.952662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:03.743292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:02.937179Z","time spent":"806.106364ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T07:24:03.743188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.958817ms","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-06-03T07:24:03.743507Z","caller":"traceutil/trace.go:171","msg":"trace[1578627370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:378; }","duration":"811.281592ms","start":"2026-06-03T07:24:02.932215Z","end":"2026-06-03T07:24:03.743497Z","steps":["trace[1578627370] 'range keys from in-memory index tree' (duration: 810.873811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:03.74355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:02.932147Z","time spent":"811.394441ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-03T07:24:04.221711Z","caller":"traceutil/trace.go:171","msg":"trace[10600550] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"474.311277ms","start":"2026-06-03T07:24:03.747377Z","end":"2026-06-03T07:24:04.221688Z","steps":["trace[10600550] 'process raft request' (duration: 474.108551ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:04.221972Z","caller":"traceutil/trace.go:171","msg":"trace[949884211] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:389; }","duration":"207.177894ms","start":"2026-06-03T07:24:04.014716Z","end":"2026-06-03T07:24:04.221894Z","steps":["trace[949884211] 'read index received' (duration: 206.707727ms)","trace[949884211] 'applied index is now lower than readState.Index' (duration: 466.836µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:24:04.222015Z","caller":"traceutil/trace.go:171","msg":"trace[1759403166] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"474.523913ms","start":"2026-06-03T07:24:03.747377Z","end":"2026-06-03T07:24:04.221901Z","steps":["trace[1759403166] 'process raft request' (duration: 474.239171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:04.222111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.38384ms","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-06-03T07:24:04.222187Z","caller":"traceutil/trace.go:171","msg":"trace[1573969423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:381; }","duration":"207.531971ms","start":"2026-06-03T07:24:04.014637Z","end":"2026-06-03T07:24:04.222169Z","steps":["trace[1573969423] 'agreement among raft nodes before linearized reading' (duration: 207.410362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:04.22231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:03.747356Z","time spent":"474.429656ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:24:04.222492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:03.747357Z","time spent":"474.73286ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:24:20.615645Z","caller":"traceutil/trace.go:171","msg":"trace[1450961706] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"747.803589ms","start":"2026-06-03T07:24:19.867781Z","end":"2026-06-03T07:24:20.615585Z","steps":["trace[1450961706] 'process raft request' (duration: 747.518746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.615829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.867773Z","time spent":"747.963891ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:24:20.627644Z","caller":"traceutil/trace.go:171","msg":"trace[301461334] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"743.776931ms","start":"2026-06-03T07:24:19.883836Z","end":"2026-06-03T07:24:20.627613Z","steps":["trace[301461334] 'process raft request' (duration: 743.652979ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:20.62767Z","caller":"traceutil/trace.go:171","msg":"trace[804424281] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"733.343139ms","start":"2026-06-03T07:24:19.894294Z","end":"2026-06-03T07:24:20.627638Z","steps":["trace[804424281] 'process raft request' (duration: 733.244755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.627791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.894276Z","time spent":"733.458882ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:24:20.627788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.883819Z","time spent":"743.892455ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:24:20.627816Z","caller":"traceutil/trace.go:171","msg":"trace[663079876] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"722.669982ms","start":"2026-06-03T07:24:19.905095Z","end":"2026-06-03T07:24:20.627765Z","steps":["trace[663079876] 'process raft request' (duration: 722.565928ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:20.627869Z","caller":"traceutil/trace.go:171","msg":"trace[285788500] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"725.604228ms","start":"2026-06-03T07:24:19.902196Z","end":"2026-06-03T07:24:20.6278Z","steps":["trace[285788500] 'process raft request' (duration: 725.435917ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:20.627839Z","caller":"traceutil/trace.go:171","msg":"trace[1437028993] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"733.409646ms","start":"2026-06-03T07:24:19.89439Z","end":"2026-06-03T07:24:20.627799Z","steps":["trace[1437028993] 'process raft request' (duration: 733.182979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.62794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.905073Z","time spent":"722.818503ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:24:20.627958Z","caller":"traceutil/trace.go:171","msg":"trace[1162869641] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"721.939845ms","start":"2026-06-03T07:24:19.905981Z","end":"2026-06-03T07:24:20.627921Z","steps":["trace[1162869641] 'process raft request' (duration: 721.735627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.627991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.902185Z","time spent":"725.73994ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:24:20.628006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.905973Z","time spent":"722.011811ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:24:20.628073Z","caller":"traceutil/trace.go:171","msg":"trace[1607780186] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:494; }","duration":"317.690188ms","start":"2026-06-03T07:24:20.310363Z","end":"2026-06-03T07:24:20.628053Z","steps":["trace[1607780186] 'read index received' (duration: 304.868613ms)","trace[1607780186] 'applied index is now lower than readState.Index' (duration: 12.821095ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:24:20.628103Z","caller":"traceutil/trace.go:171","msg":"trace[1071520636] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"673.21827ms","start":"2026-06-03T07:24:19.954877Z","end":"2026-06-03T07:24:20.628095Z","steps":["trace[1071520636] 'process raft request' (duration: 673.057359ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:20.628208Z","caller":"traceutil/trace.go:171","msg":"trace[1527858793] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"567.882405ms","start":"2026-06-03T07:24:20.060304Z","end":"2026-06-03T07:24:20.628187Z","steps":["trace[1527858793] 'process raft request' (duration: 567.694385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.628216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.85496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-03T07:24:20.628212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.894359Z","time spent":"733.571846ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:24:20.628243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.954853Z","time spent":"673.372561ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:24:20.628246Z","caller":"traceutil/trace.go:171","msg":"trace[1110504345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:493; }","duration":"317.924916ms","start":"2026-06-03T07:24:20.310312Z","end":"2026-06-03T07:24:20.628237Z","steps":["trace[1110504345] 'agreement among raft nodes before linearized reading' (duration: 317.825001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.628269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:20.310288Z","time spent":"317.976022ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T07:24:20.62825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:20.060274Z","time spent":"567.957121ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:24:20.628276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.842808ms","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-06-03T07:24:20.628297Z","caller":"traceutil/trace.go:171","msg":"trace[1856061892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"268.880896ms","start":"2026-06-03T07:24:20.359409Z","end":"2026-06-03T07:24:20.62829Z","steps":["trace[1856061892] 'agreement among raft nodes before linearized reading' (duration: 268.80413ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:20.628135Z","caller":"traceutil/trace.go:171","msg":"trace[1093511324] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"702.971008ms","start":"2026-06-03T07:24:19.925122Z","end":"2026-06-03T07:24:20.628093Z","steps":["trace[1093511324] 'process raft request' (duration: 702.7596ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:20.627968Z","caller":"traceutil/trace.go:171","msg":"trace[702398056] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"722.784484ms","start":"2026-06-03T07:24:19.905125Z","end":"2026-06-03T07:24:20.62791Z","steps":["trace[702398056] 'process raft request' (duration: 722.563447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.628362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.925106Z","time spent":"703.223493ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:24:20.628386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.905115Z","time spent":"723.23059ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:24:20.62816Z","caller":"traceutil/trace.go:171","msg":"trace[1560921901] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"711.983024ms","start":"2026-06-03T07:24:19.916171Z","end":"2026-06-03T07:24:20.628154Z","steps":["trace[1560921901] 'process raft request' (duration: 711.580458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.628465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.916133Z","time spent":"712.311396ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:24:20.628197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.270613ms","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-06-03T07:24:20.628637Z","caller":"traceutil/trace.go:171","msg":"trace[1009519115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"317.708428ms","start":"2026-06-03T07:24:20.310896Z","end":"2026-06-03T07:24:20.628605Z","steps":["trace[1009519115] 'agreement among raft nodes before linearized reading' (duration: 317.246784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.62871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:20.310888Z","time spent":"317.784164ms","remote":"127.0.0.1:51294","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":"info","ts":"2026-06-03T07:24:20.628542Z","caller":"traceutil/trace.go:171","msg":"trace[1609190871] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"729.385944ms","start":"2026-06-03T07:24:19.898329Z","end":"2026-06-03T07:24:20.627715Z","steps":["trace[1609190871] 'process raft request' (duration: 729.27393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:20.629236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:19.89832Z","time spent":"730.875399ms","remote":"127.0.0.1:51036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:24:26.962527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.090948ms","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-06-03T07:24:26.962646Z","caller":"traceutil/trace.go:171","msg":"trace[1171266178] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:580; }","duration":"109.223211ms","start":"2026-06-03T07:24:26.853408Z","end":"2026-06-03T07:24:26.962631Z","steps":["trace[1171266178] 'range keys from in-memory index tree' (duration: 109.075938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:26.962977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.927775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:24:26.963211Z","caller":"traceutil/trace.go:171","msg":"trace[2100366672] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"236.982531ms","start":"2026-06-03T07:24:26.726217Z","end":"2026-06-03T07:24:26.963199Z","steps":["trace[2100366672] 'process raft request' (duration: 99.54178ms)","trace[2100366672] 'compare' (duration: 136.741324ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:24:26.963268Z","caller":"traceutil/trace.go:171","msg":"trace[739729012] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"236.982232ms","start":"2026-06-03T07:24:26.726267Z","end":"2026-06-03T07:24:26.963249Z","steps":["trace[739729012] 'process raft request' (duration: 236.833019ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:26.963412Z","caller":"traceutil/trace.go:171","msg":"trace[211702813] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"235.095728ms","start":"2026-06-03T07:24:26.728249Z","end":"2026-06-03T07:24:26.963345Z","steps":["trace[211702813] 'process raft request' (duration: 234.913377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:27.438541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262227529025056299,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T07:24:27.668618Z","caller":"traceutil/trace.go:171","msg":"trace[1471053196] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"730.481125ms","start":"2026-06-03T07:24:26.938111Z","end":"2026-06-03T07:24:27.668592Z","steps":["trace[1471053196] 'read index received' (duration: 711.325221ms)","trace[1471053196] 'applied index is now lower than readState.Index' (duration: 19.154954ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:24:27.668752Z","caller":"traceutil/trace.go:171","msg":"trace[303271714] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"783.459317ms","start":"2026-06-03T07:24:26.885249Z","end":"2026-06-03T07:24:27.668708Z","steps":["trace[303271714] 'process raft request' (duration: 764.712585ms)","trace[303271714] 'compare' (duration: 17.779365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:24:27.668748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.625089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:24:27.668955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:26.885218Z","time spent":"783.644529ms","remote":"127.0.0.1:51110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:24:27.668977Z","caller":"traceutil/trace.go:171","msg":"trace[1327838177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"730.898937ms","start":"2026-06-03T07:24:26.938055Z","end":"2026-06-03T07:24:27.668953Z","steps":["trace[1327838177] 'agreement among raft nodes before linearized reading' (duration: 730.621919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:27.669037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:26.938029Z","time spent":"730.991933ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T07:24:28.994305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:27.989193Z","time spent":"1.005105767s","remote":"127.0.0.1:41952","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-03T07:24:29.088932Z","caller":"traceutil/trace.go:171","msg":"trace[34653883] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"919.782932ms","start":"2026-06-03T07:24:28.169125Z","end":"2026-06-03T07:24:29.088908Z","steps":["trace[34653883] 'process raft request' (duration: 919.241114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:29.089123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:28.169075Z","time spent":"919.931336ms","remote":"127.0.0.1:51110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:24:29.09243Z","caller":"traceutil/trace.go:171","msg":"trace[1577522354] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"373.127927ms","start":"2026-06-03T07:24:28.719269Z","end":"2026-06-03T07:24:29.092397Z","steps":["trace[1577522354] 'read index received' (duration: 369.063146ms)","trace[1577522354] 'applied index is now lower than readState.Index' (duration: 4.063042ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:24:29.092521Z","caller":"traceutil/trace.go:171","msg":"trace[1317537791] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"682.234574ms","start":"2026-06-03T07:24:28.41027Z","end":"2026-06-03T07:24:29.092505Z","steps":["trace[1317537791] 'process raft request' (duration: 681.973245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:29.092599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:28.410246Z","time spent":"682.312361ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:24:29.09277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.866581ms","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-06-03T07:24:29.092835Z","caller":"traceutil/trace.go:171","msg":"trace[1132122040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"125.980237ms","start":"2026-06-03T07:24:28.966839Z","end":"2026-06-03T07:24:29.092819Z","steps":["trace[1132122040] 'agreement among raft nodes before linearized reading' (duration: 125.773906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:29.092852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.640153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-03T07:24:29.093028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.060684ms","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-06-03T07:24:29.09306Z","caller":"traceutil/trace.go:171","msg":"trace[1459788951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:587; }","duration":"126.110321ms","start":"2026-06-03T07:24:28.966939Z","end":"2026-06-03T07:24:29.09305Z","steps":["trace[1459788951] 'agreement among raft nodes before linearized reading' (duration: 126.002026ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:29.093041Z","caller":"traceutil/trace.go:171","msg":"trace[1755118736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"344.836925ms","start":"2026-06-03T07:24:28.748186Z","end":"2026-06-03T07:24:29.093023Z","steps":["trace[1755118736] 'agreement among raft nodes before linearized reading' (duration: 344.515618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:29.092903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.545205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:24:29.093274Z","caller":"traceutil/trace.go:171","msg":"trace[678500129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"155.944409ms","start":"2026-06-03T07:24:28.937315Z","end":"2026-06-03T07:24:29.093259Z","steps":["trace[678500129] 'agreement among raft nodes before linearized reading' (duration: 155.555105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:29.093435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:28.748132Z","time spent":"344.977489ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T07:24:29.093578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.299549ms","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-06-03T07:24:29.093645Z","caller":"traceutil/trace.go:171","msg":"trace[28812646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"374.395134ms","start":"2026-06-03T07:24:28.719238Z","end":"2026-06-03T07:24:29.093633Z","steps":["trace[28812646] 'agreement among raft nodes before linearized reading' (duration: 373.311459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:29.09368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:28.719223Z","time spent":"374.447133ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T07:24:31.061053Z","caller":"traceutil/trace.go:171","msg":"trace[1218910470] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"123.591222ms","start":"2026-06-03T07:24:30.937433Z","end":"2026-06-03T07:24:31.061025Z","steps":["trace[1218910470] 'read index received' (duration: 123.461927ms)","trace[1218910470] 'applied index is now lower than readState.Index' (duration: 128.095µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:24:31.061128Z","caller":"traceutil/trace.go:171","msg":"trace[634221357] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"137.73663ms","start":"2026-06-03T07:24:30.923354Z","end":"2026-06-03T07:24:31.06109Z","steps":["trace[634221357] 'process raft request' (duration: 137.536978ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:31.061249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.785974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:24:31.061309Z","caller":"traceutil/trace.go:171","msg":"trace[1815245873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"123.87966ms","start":"2026-06-03T07:24:30.937413Z","end":"2026-06-03T07:24:31.061292Z","steps":["trace[1815245873] 'agreement among raft nodes before linearized reading' (duration: 123.731556ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:24:45.121226Z","caller":"traceutil/trace.go:171","msg":"trace[243378344] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"526.648941ms","start":"2026-06-03T07:24:44.594551Z","end":"2026-06-03T07:24:45.1212Z","steps":["trace[243378344] 'process raft request' (duration: 526.488934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:45.121522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:44.594529Z","time spent":"526.773238ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:24:45.152156Z","caller":"traceutil/trace.go:171","msg":"trace[384375159] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:781; }","duration":"265.795147ms","start":"2026-06-03T07:24:44.886316Z","end":"2026-06-03T07:24:45.152111Z","steps":["trace[384375159] 'read index received' (duration: 234.724737ms)","trace[384375159] 'applied index is now lower than readState.Index' (duration: 31.06949ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:24:45.152182Z","caller":"traceutil/trace.go:171","msg":"trace[713797474] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"379.045321ms","start":"2026-06-03T07:24:44.77311Z","end":"2026-06-03T07:24:45.152155Z","steps":["trace[713797474] 'process raft request' (duration: 378.843784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:45.152315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.977644ms","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-06-03T07:24:45.152367Z","caller":"traceutil/trace.go:171","msg":"trace[595571643] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:757; }","duration":"266.078872ms","start":"2026-06-03T07:24:44.886278Z","end":"2026-06-03T07:24:45.152356Z","steps":["trace[595571643] 'agreement among raft nodes before linearized reading' (duration: 265.967574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:24:45.152474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:24:44.773083Z","time spent":"379.263246ms","remote":"127.0.0.1:51262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:24:45.15256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.580477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:24:45.152669Z","caller":"traceutil/trace.go:171","msg":"trace[61580417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"214.689015ms","start":"2026-06-03T07:24:44.937962Z","end":"2026-06-03T07:24:45.152651Z","steps":["trace[61580417] 'agreement among raft nodes before linearized reading' (duration: 214.510708ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:02.901348Z","caller":"traceutil/trace.go:171","msg":"trace[845431950] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"141.232296ms","start":"2026-06-03T07:25:02.760088Z","end":"2026-06-03T07:25:02.90132Z","steps":["trace[845431950] 'process raft request' (duration: 96.375596ms)","trace[845431950] 'compare' (duration: 44.59599ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:25:02.903216Z","caller":"traceutil/trace.go:171","msg":"trace[515272077] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"100.630091ms","start":"2026-06-03T07:25:02.802575Z","end":"2026-06-03T07:25:02.903206Z","steps":["trace[515272077] 'process raft request' (duration: 100.148761ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:03.107749Z","caller":"traceutil/trace.go:171","msg":"trace[1616719759] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1102; }","duration":"142.181056ms","start":"2026-06-03T07:25:02.965549Z","end":"2026-06-03T07:25:03.10773Z","steps":["trace[1616719759] 'read index received' (duration: 100.9126ms)","trace[1616719759] 'applied index is now lower than readState.Index' (duration: 41.267796ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:25:03.107783Z","caller":"traceutil/trace.go:171","msg":"trace[814294415] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"142.256916ms","start":"2026-06-03T07:25:02.965494Z","end":"2026-06-03T07:25:03.107751Z","steps":["trace[814294415] 'process raft request' (duration: 142.212806ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:03.107777Z","caller":"traceutil/trace.go:171","msg":"trace[2115591416] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"156.036321ms","start":"2026-06-03T07:25:02.951706Z","end":"2026-06-03T07:25:03.107742Z","steps":["trace[2115591416] 'process raft request' (duration: 155.945181ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:03.107766Z","caller":"traceutil/trace.go:171","msg":"trace[1648713118] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"156.375142ms","start":"2026-06-03T07:25:02.951349Z","end":"2026-06-03T07:25:03.107724Z","steps":["trace[1648713118] 'process raft request' (duration: 115.102896ms)","trace[1648713118] 'compare' (duration: 41.111816ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:25:03.107914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.347106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-q9w56\" ","response":"range_response_count:1 size:2916"} {"level":"info","ts":"2026-06-03T07:25:03.109302Z","caller":"traceutil/trace.go:171","msg":"trace[721544097] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-q9w56; range_end:; response_count:1; response_revision:1072; }","duration":"143.758889ms","start":"2026-06-03T07:25:02.965522Z","end":"2026-06-03T07:25:03.109281Z","steps":["trace[721544097] 'agreement among raft nodes before linearized reading' (duration: 142.287026ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:03.489389Z","caller":"traceutil/trace.go:171","msg":"trace[1779982541] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"203.574743ms","start":"2026-06-03T07:25:03.285761Z","end":"2026-06-03T07:25:03.489336Z","steps":["trace[1779982541] 'process raft request' (duration: 202.350041ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:03.530918Z","caller":"traceutil/trace.go:171","msg":"trace[785934842] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"243.069395ms","start":"2026-06-03T07:25:03.287819Z","end":"2026-06-03T07:25:03.530888Z","steps":["trace[785934842] 'process raft request' (duration: 242.846345ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:03.530965Z","caller":"traceutil/trace.go:171","msg":"trace[1750862015] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"243.071436ms","start":"2026-06-03T07:25:03.287875Z","end":"2026-06-03T07:25:03.530946Z","steps":["trace[1750862015] 'process raft request' (duration: 242.938245ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:03.530961Z","caller":"traceutil/trace.go:171","msg":"trace[819574968] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"176.717367ms","start":"2026-06-03T07:25:03.354214Z","end":"2026-06-03T07:25:03.530932Z","steps":["trace[819574968] 'process raft request' (duration: 176.646737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:04.469103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.199169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:25:04.469205Z","caller":"traceutil/trace.go:171","msg":"trace[269696344] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1084; }","duration":"207.3731ms","start":"2026-06-03T07:25:04.261809Z","end":"2026-06-03T07:25:04.469182Z","steps":["trace[269696344] 'count revisions from in-memory index tree' (duration: 207.067379ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:25:05.586668Z","caller":"traceutil/trace.go:171","msg":"trace[1410237315] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"299.661482ms","start":"2026-06-03T07:25:05.286985Z","end":"2026-06-03T07:25:05.586646Z","steps":["trace[1410237315] 'read index received' (duration: 299.370861ms)","trace[1410237315] 'applied index is now lower than readState.Index' (duration: 289.591µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:25:05.586764Z","caller":"traceutil/trace.go:171","msg":"trace[1722049115] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"359.712921ms","start":"2026-06-03T07:25:05.227023Z","end":"2026-06-03T07:25:05.586736Z","steps":["trace[1722049115] 'process raft request' (duration: 359.37003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:05.58691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:25:05.226987Z","time spent":"359.843462ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:25:05.586983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.991764ms","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-06-03T07:25:05.587058Z","caller":"traceutil/trace.go:171","msg":"trace[1173525303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"300.129135ms","start":"2026-06-03T07:25:05.286915Z","end":"2026-06-03T07:25:05.587045Z","steps":["trace[1173525303] 'agreement among raft nodes before linearized reading' (duration: 299.909184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:05.5871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:25:05.286882Z","time spent":"300.207055ms","remote":"127.0.0.1:51294","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":"info","ts":"2026-06-03T07:25:16.007062Z","caller":"traceutil/trace.go:171","msg":"trace[1501591362] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"366.520012ms","start":"2026-06-03T07:25:15.640242Z","end":"2026-06-03T07:25:16.006762Z","steps":["trace[1501591362] 'process raft request' (duration: 366.333399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:16.007266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:25:15.640224Z","time spent":"366.919997ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:25:16.009332Z","caller":"traceutil/trace.go:171","msg":"trace[1680011913] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"367.622777ms","start":"2026-06-03T07:25:15.641687Z","end":"2026-06-03T07:25:16.00931Z","steps":["trace[1680011913] 'process raft request' (duration: 367.411044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:16.009475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.292881ms","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-06-03T07:25:16.009249Z","caller":"traceutil/trace.go:171","msg":"trace[1695646507] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1192; }","duration":"366.059128ms","start":"2026-06-03T07:25:15.643167Z","end":"2026-06-03T07:25:16.009226Z","steps":["trace[1695646507] 'read index received' (duration: 363.440023ms)","trace[1695646507] 'applied index is now lower than readState.Index' (duration: 2.618195ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:25:16.009538Z","caller":"traceutil/trace.go:171","msg":"trace[666722862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"366.379322ms","start":"2026-06-03T07:25:15.643144Z","end":"2026-06-03T07:25:16.009523Z","steps":["trace[666722862] 'agreement among raft nodes before linearized reading' (duration: 366.21895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:16.009575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:25:15.643127Z","time spent":"366.439943ms","remote":"127.0.0.1:51294","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-06-03T07:25:16.009741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:25:15.641678Z","time spent":"367.716388ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:25:16.009779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.504324ms","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-06-03T07:25:16.009858Z","caller":"traceutil/trace.go:171","msg":"trace[284464213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"366.693986ms","start":"2026-06-03T07:25:15.643144Z","end":"2026-06-03T07:25:16.009838Z","steps":["trace[284464213] 'agreement among raft nodes before linearized reading' (duration: 366.378952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:16.009801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.649277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.185\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-03T07:25:16.009924Z","caller":"traceutil/trace.go:171","msg":"trace[1915418405] range","detail":"{range_begin:/registry/masterleases/199.19.213.185; range_end:; response_count:1; response_revision:1159; }","duration":"298.810108ms","start":"2026-06-03T07:25:15.711102Z","end":"2026-06-03T07:25:16.009912Z","steps":["trace[1915418405] 'agreement among raft nodes before linearized reading' (duration: 298.596516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:16.00991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:25:15.643134Z","time spent":"366.765837ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T07:25:16.010415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.166143ms","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-06-03T07:25:16.010599Z","caller":"traceutil/trace.go:171","msg":"trace[573123277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"367.378325ms","start":"2026-06-03T07:25:15.643186Z","end":"2026-06-03T07:25:16.010564Z","steps":["trace[573123277] 'agreement among raft nodes before linearized reading' (duration: 366.446753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:25:16.010623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:25:15.643164Z","time spent":"367.451797ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:26:07.733409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.624775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40200d8a-893b-4caa-9896-3847964855ee\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-03T07:26:07.733506Z","caller":"traceutil/trace.go:171","msg":"trace[758218585] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40200d8a-893b-4caa-9896-3847964855ee; range_end:; response_count:1; response_revision:1848; }","duration":"277.776571ms","start":"2026-06-03T07:26:07.455708Z","end":"2026-06-03T07:26:07.733485Z","steps":["trace[758218585] 'range keys from in-memory index tree' (duration: 277.453428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.762695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262227529025058795,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T07:26:08.940916Z","caller":"traceutil/trace.go:171","msg":"trace[1896222902] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"794.593253ms","start":"2026-06-03T07:26:08.1463Z","end":"2026-06-03T07:26:08.940893Z","steps":["trace[1896222902] 'process raft request' (duration: 794.416256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.941084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.146283Z","time spent":"794.714428ms","remote":"127.0.0.1:51200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:26:08.946307Z","caller":"traceutil/trace.go:171","msg":"trace[1834960782] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"795.624076ms","start":"2026-06-03T07:26:08.150665Z","end":"2026-06-03T07:26:08.946289Z","steps":["trace[1834960782] 'process raft request' (duration: 795.46258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.946433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.15065Z","time spent":"795.73719ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:26:08.946484Z","caller":"traceutil/trace.go:171","msg":"trace[1757738595] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1921; }","duration":"684.817953ms","start":"2026-06-03T07:26:08.261654Z","end":"2026-06-03T07:26:08.946472Z","steps":["trace[1757738595] 'read index received' (duration: 679.200431ms)","trace[1757738595] 'applied index is now lower than readState.Index' (duration: 5.616542ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:26:08.946317Z","caller":"traceutil/trace.go:171","msg":"trace[310917515] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"605.010952ms","start":"2026-06-03T07:26:08.34128Z","end":"2026-06-03T07:26:08.946291Z","steps":["trace[310917515] 'process raft request' (duration: 604.95321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.946737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.496998ms","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-06-03T07:26:08.946784Z","caller":"traceutil/trace.go:171","msg":"trace[531901216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1852; }","duration":"587.56609ms","start":"2026-06-03T07:26:08.359204Z","end":"2026-06-03T07:26:08.94677Z","steps":["trace[531901216] 'agreement among raft nodes before linearized reading' (duration: 587.447665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.946758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.030904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-40200d8a-893b-4caa-9896-3847964855ee\" ","response":"range_response_count:1 size:3744"} {"level":"warn","ts":"2026-06-03T07:26:08.946819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.359186Z","time spent":"587.624583ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T07:26:08.946882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.492443ms","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-06-03T07:26:08.946906Z","caller":"traceutil/trace.go:171","msg":"trace[557217850] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-40200d8a-893b-4caa-9896-3847964855ee; range_end:; response_count:1; response_revision:1852; }","duration":"208.137578ms","start":"2026-06-03T07:26:08.738711Z","end":"2026-06-03T07:26:08.946848Z","steps":["trace[557217850] 'agreement among raft nodes before linearized reading' (duration: 207.908159ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:08.946943Z","caller":"traceutil/trace.go:171","msg":"trace[191293310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1852; }","duration":"606.739684ms","start":"2026-06-03T07:26:08.340193Z","end":"2026-06-03T07:26:08.946932Z","steps":["trace[191293310] 'agreement among raft nodes before linearized reading' (duration: 606.421261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.947024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.340172Z","time spent":"606.840478ms","remote":"127.0.0.1:51294","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-06-03T07:26:08.947023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.393424ms","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-06-03T07:26:08.947166Z","caller":"traceutil/trace.go:171","msg":"trace[1097352687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1852; }","duration":"580.552391ms","start":"2026-06-03T07:26:08.366603Z","end":"2026-06-03T07:26:08.947155Z","steps":["trace[1097352687] 'agreement among raft nodes before linearized reading' (duration: 580.336922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.947217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.366593Z","time spent":"580.614213ms","remote":"127.0.0.1:51294","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-06-03T07:26:08.947265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.392426ms","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-06-03T07:26:08.947312Z","caller":"traceutil/trace.go:171","msg":"trace[469132704] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"206.465269ms","start":"2026-06-03T07:26:08.740835Z","end":"2026-06-03T07:26:08.9473Z","steps":["trace[469132704] 'agreement among raft nodes before linearized reading' (duration: 206.367945ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.947167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.825907ms","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-06-03T07:26:08.947356Z","caller":"traceutil/trace.go:171","msg":"trace[1751330207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1852; }","duration":"607.051637ms","start":"2026-06-03T07:26:08.340291Z","end":"2026-06-03T07:26:08.947343Z","steps":["trace[1751330207] 'agreement among raft nodes before linearized reading' (duration: 606.756855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.947049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.388967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-03T07:26:08.947455Z","caller":"traceutil/trace.go:171","msg":"trace[293087522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"685.881327ms","start":"2026-06-03T07:26:08.261561Z","end":"2026-06-03T07:26:08.947443Z","steps":["trace[293087522] 'agreement among raft nodes before linearized reading' (duration: 685.371776ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:08.947431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.341263Z","time spent":"605.479102ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:26:08.947485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.261537Z","time spent":"685.941219ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T07:26:08.947423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:08.340266Z","time spent":"607.145561ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-03T07:26:14.804899Z","caller":"traceutil/trace.go:171","msg":"trace[5282580] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"127.8201ms","start":"2026-06-03T07:26:14.677059Z","end":"2026-06-03T07:26:14.804879Z","steps":["trace[5282580] 'process raft request' (duration: 127.686304ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:43.327237Z","caller":"traceutil/trace.go:171","msg":"trace[1773445331] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"213.043559ms","start":"2026-06-03T07:26:43.114162Z","end":"2026-06-03T07:26:43.327205Z","steps":["trace[1773445331] 'process raft request' (duration: 212.892582ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:43.596902Z","caller":"traceutil/trace.go:171","msg":"trace[1307349065] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"470.409086ms","start":"2026-06-03T07:26:43.126466Z","end":"2026-06-03T07:26:43.596876Z","steps":["trace[1307349065] 'process raft request' (duration: 470.197056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:43.597062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.126438Z","time spent":"470.544123ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:26:43.59708Z","caller":"traceutil/trace.go:171","msg":"trace[1724663462] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"297.624597ms","start":"2026-06-03T07:26:43.299425Z","end":"2026-06-03T07:26:43.59705Z","steps":["trace[1724663462] 'process raft request' (duration: 297.410946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:43.597474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.069961ms","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-06-03T07:26:43.59758Z","caller":"traceutil/trace.go:171","msg":"trace[1826127651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2087; }","duration":"444.243719ms","start":"2026-06-03T07:26:43.153307Z","end":"2026-06-03T07:26:43.597551Z","steps":["trace[1826127651] 'agreement among raft nodes before linearized reading' (duration: 443.798287ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:43.597639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.153301Z","time spent":"444.322033ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-03T07:26:43.599236Z","caller":"traceutil/trace.go:171","msg":"trace[707904788] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"443.721063ms","start":"2026-06-03T07:26:43.153328Z","end":"2026-06-03T07:26:43.597049Z","steps":["trace[707904788] 'read index received' (duration: 173.950258ms)","trace[707904788] 'applied index is now lower than readState.Index' (duration: 269.768855ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:26:43.599544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.994635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-03T07:26:43.599587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.904103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-03T07:26:43.599711Z","caller":"traceutil/trace.go:171","msg":"trace[295587374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2087; }","duration":"431.069161ms","start":"2026-06-03T07:26:43.16863Z","end":"2026-06-03T07:26:43.599699Z","steps":["trace[295587374] 'agreement among raft nodes before linearized reading' (duration: 430.829609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:43.599688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.151742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-03T07:26:43.599731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.946393ms","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-06-03T07:26:43.599589Z","caller":"traceutil/trace.go:171","msg":"trace[2076419531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2087; }","duration":"442.08154ms","start":"2026-06-03T07:26:43.157493Z","end":"2026-06-03T07:26:43.599574Z","steps":["trace[2076419531] 'agreement among raft nodes before linearized reading' (duration: 441.89131ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:43.599767Z","caller":"traceutil/trace.go:171","msg":"trace[1839720269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2087; }","duration":"426.274889ms","start":"2026-06-03T07:26:43.173472Z","end":"2026-06-03T07:26:43.599747Z","steps":["trace[1839720269] 'agreement among raft nodes before linearized reading' (duration: 426.060538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:43.599825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.173454Z","time spent":"426.361143ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T07:26:43.599603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.980943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-03T07:26:43.599842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.418212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-03T07:26:43.599842Z","caller":"traceutil/trace.go:171","msg":"trace[312653472] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"413.012676ms","start":"2026-06-03T07:26:43.186746Z","end":"2026-06-03T07:26:43.599759Z","steps":["trace[312653472] 'agreement among raft nodes before linearized reading' (duration: 412.656618ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:43.599904Z","caller":"traceutil/trace.go:171","msg":"trace[264109584] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2087; }","duration":"232.491045ms","start":"2026-06-03T07:26:43.367377Z","end":"2026-06-03T07:26:43.599868Z","steps":["trace[264109584] 'agreement among raft nodes before linearized reading' (duration: 232.268764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:43.599746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.168615Z","time spent":"431.120594ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:26:43.599834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.157474Z","time spent":"442.337943ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-03T07:26:43.599916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.18673Z","time spent":"413.175485ms","remote":"127.0.0.1:60954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-03T07:26:43.59997Z","caller":"traceutil/trace.go:171","msg":"trace[1884485701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2087; }","duration":"439.300809ms","start":"2026-06-03T07:26:43.160571Z","end":"2026-06-03T07:26:43.599872Z","steps":["trace[1884485701] 'agreement among raft nodes before linearized reading' (duration: 438.905369ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:43.600142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.160553Z","time spent":"439.51994ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-03T07:26:44.158657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262227529025059323,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-03T07:26:44.275759Z","caller":"traceutil/trace.go:171","msg":"trace[1050492004] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"670.548404ms","start":"2026-06-03T07:26:43.605191Z","end":"2026-06-03T07:26:44.275739Z","steps":["trace[1050492004] 'process raft request' (duration: 670.423718ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:44.275891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.605177Z","time spent":"670.637699ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:26:44.280935Z","caller":"traceutil/trace.go:171","msg":"trace[1136464944] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"675.551378ms","start":"2026-06-03T07:26:43.605363Z","end":"2026-06-03T07:26:44.280914Z","steps":["trace[1136464944] 'process raft request' (duration: 675.408301ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:44.280984Z","caller":"traceutil/trace.go:171","msg":"trace[1936463365] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"674.809051ms","start":"2026-06-03T07:26:43.606156Z","end":"2026-06-03T07:26:44.280965Z","steps":["trace[1936463365] 'process raft request' (duration: 674.757338ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:44.281002Z","caller":"traceutil/trace.go:171","msg":"trace[1404543432] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"675.406561ms","start":"2026-06-03T07:26:43.605566Z","end":"2026-06-03T07:26:44.280973Z","steps":["trace[1404543432] 'process raft request' (duration: 675.291725ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:44.281118Z","caller":"traceutil/trace.go:171","msg":"trace[104096230] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"673.038121ms","start":"2026-06-03T07:26:43.60807Z","end":"2026-06-03T07:26:44.281109Z","steps":["trace[104096230] 'process raft request' (duration: 672.866742ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:26:44.281075Z","caller":"traceutil/trace.go:171","msg":"trace[2062605828] linearizableReadLoop","detail":"{readStateIndex:2174; appliedIndex:2168; }","duration":"623.384225ms","start":"2026-06-03T07:26:43.657674Z","end":"2026-06-03T07:26:44.281058Z","steps":["trace[2062605828] 'read index received' (duration: 617.918357ms)","trace[2062605828] 'applied index is now lower than readState.Index' (duration: 5.464498ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:26:44.281187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.606145Z","time spent":"674.891516ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:26:44.281173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.608053Z","time spent":"673.093304ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:26:44.281231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.605345Z","time spent":"675.633393ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:26:44.281284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.592384ms","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-06-03T07:26:44.281365Z","caller":"traceutil/trace.go:171","msg":"trace[500141632] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2093; }","duration":"623.727061ms","start":"2026-06-03T07:26:43.657624Z","end":"2026-06-03T07:26:44.281351Z","steps":["trace[500141632] 'agreement among raft nodes before linearized reading' (duration: 623.532562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:44.281443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.657611Z","time spent":"623.818736ms","remote":"127.0.0.1:51290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:26:44.281328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.21815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-03T07:26:44.281517Z","caller":"traceutil/trace.go:171","msg":"trace[1525954882] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:2093; }","duration":"145.478503ms","start":"2026-06-03T07:26:44.136027Z","end":"2026-06-03T07:26:44.281506Z","steps":["trace[1525954882] 'agreement among raft nodes before linearized reading' (duration: 145.242781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:44.281543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.309976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126467"} {"level":"info","ts":"2026-06-03T07:26:44.281583Z","caller":"traceutil/trace.go:171","msg":"trace[1574037636] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2093; }","duration":"147.38565ms","start":"2026-06-03T07:26:44.134186Z","end":"2026-06-03T07:26:44.281572Z","steps":["trace[1574037636] 'agreement among raft nodes before linearized reading' (duration: 147.153198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:44.281912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.605553Z","time spent":"676.302217ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:26:44.282374Z","caller":"traceutil/trace.go:171","msg":"trace[1322196339] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"676.229183ms","start":"2026-06-03T07:26:43.605661Z","end":"2026-06-03T07:26:44.28189Z","steps":["trace[1322196339] 'process raft request' (duration: 675.226792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:44.282631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.605654Z","time spent":"676.852495ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:26:44.28397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.144402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:26:44.284094Z","caller":"traceutil/trace.go:171","msg":"trace[2099439061] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2093; }","duration":"393.973835ms","start":"2026-06-03T07:26:43.890062Z","end":"2026-06-03T07:26:44.284036Z","steps":["trace[2099439061] 'agreement among raft nodes before linearized reading' (duration: 391.157622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:26:44.284287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:26:43.890042Z","time spent":"394.091981ms","remote":"127.0.0.1:39286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:27:41.111826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.85239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12209"} {"level":"info","ts":"2026-06-03T07:27:41.111909Z","caller":"traceutil/trace.go:171","msg":"trace[2015744767] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2445; }","duration":"111.987348ms","start":"2026-06-03T07:27:40.999902Z","end":"2026-06-03T07:27:41.11189Z","steps":["trace[2015744767] 'range keys from in-memory index tree' (duration: 111.634598ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:27:49.832698Z","caller":"traceutil/trace.go:171","msg":"trace[1743857826] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"445.34904ms","start":"2026-06-03T07:27:49.387318Z","end":"2026-06-03T07:27:49.832667Z","steps":["trace[1743857826] 'process raft request' (duration: 445.134437ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:27:49.832693Z","caller":"traceutil/trace.go:171","msg":"trace[1767227612] linearizableReadLoop","detail":"{readStateIndex:2640; appliedIndex:2639; }","duration":"391.388621ms","start":"2026-06-03T07:27:49.441222Z","end":"2026-06-03T07:27:49.83261Z","steps":["trace[1767227612] 'read index received' (duration: 391.251153ms)","trace[1767227612] 'applied index is now lower than readState.Index' (duration: 136.488µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:27:49.832879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:27:49.3873Z","time spent":"445.489788ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:27:49.832874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.622835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:27:49.833209Z","caller":"traceutil/trace.go:171","msg":"trace[103808382] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2544; }","duration":"391.976445ms","start":"2026-06-03T07:27:49.441211Z","end":"2026-06-03T07:27:49.833188Z","steps":["trace[103808382] 'agreement among raft nodes before linearized reading' (duration: 391.583242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:27:49.833398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:27:49.441149Z","time spent":"392.22478ms","remote":"127.0.0.1:52206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-06-03T07:27:49.909057Z","caller":"traceutil/trace.go:171","msg":"trace[674955304] transaction","detail":"{read_only:false; response_revision:2545; number_of_response:1; }","duration":"352.210144ms","start":"2026-06-03T07:27:49.55682Z","end":"2026-06-03T07:27:49.90903Z","steps":["trace[674955304] 'process raft request' (duration: 352.031454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:27:49.909351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:27:49.556802Z","time spent":"352.333141ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:27:49.909505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.695856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-03T07:27:49.910308Z","caller":"traceutil/trace.go:171","msg":"trace[2107175961] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2545; }","duration":"359.648272ms","start":"2026-06-03T07:27:49.550635Z","end":"2026-06-03T07:27:49.910284Z","steps":["trace[2107175961] 'agreement among raft nodes before linearized reading' (duration: 358.364306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:27:49.910391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:27:49.550615Z","time spent":"359.753419ms","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-03T07:28:07.23137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262227529025060665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T07:28:07.659257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.220238ms","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-06-03T07:28:07.659376Z","caller":"traceutil/trace.go:171","msg":"trace[318978122] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2641; }","duration":"805.347046ms","start":"2026-06-03T07:28:06.854011Z","end":"2026-06-03T07:28:07.659358Z","steps":["trace[318978122] 'range keys from in-memory index tree' (duration: 805.205517ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:07.660018Z","caller":"traceutil/trace.go:171","msg":"trace[1185618049] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"243.746215ms","start":"2026-06-03T07:28:07.416257Z","end":"2026-06-03T07:28:07.660003Z","steps":["trace[1185618049] 'process raft request' (duration: 243.669261ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:07.660151Z","caller":"traceutil/trace.go:171","msg":"trace[332386871] linearizableReadLoop","detail":"{readStateIndex:2743; appliedIndex:2742; }","duration":"929.668928ms","start":"2026-06-03T07:28:06.730452Z","end":"2026-06-03T07:28:07.660121Z","steps":["trace[332386871] 'read index received' (duration: 928.117385ms)","trace[332386871] 'applied index is now lower than readState.Index' (duration: 1.548923ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:28:07.660185Z","caller":"traceutil/trace.go:171","msg":"trace[1535832893] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"960.468065ms","start":"2026-06-03T07:28:06.69966Z","end":"2026-06-03T07:28:07.660128Z","steps":["trace[1535832893] 'process raft request' (duration: 958.88949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:07.660758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:06.699638Z","time spent":"961.060621ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:28:07.660412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.940835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-06-03T07:28:07.660937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.607215ms","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-06-03T07:28:07.660933Z","caller":"traceutil/trace.go:171","msg":"trace[1886945986] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2643; }","duration":"930.503559ms","start":"2026-06-03T07:28:06.730409Z","end":"2026-06-03T07:28:07.660913Z","steps":["trace[1886945986] 'agreement among raft nodes before linearized reading' (duration: 929.803377ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:07.661052Z","caller":"traceutil/trace.go:171","msg":"trace[1688946153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2643; }","duration":"926.68123ms","start":"2026-06-03T07:28:06.734292Z","end":"2026-06-03T07:28:07.660974Z","steps":["trace[1688946153] 'agreement among raft nodes before linearized reading' (duration: 926.368021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:07.661096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:06.734284Z","time spent":"926.800716ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T07:28:07.661193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.671571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-06-03T07:28:07.661278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.019262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T07:28:07.66128Z","caller":"traceutil/trace.go:171","msg":"trace[999934433] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2643; }","duration":"109.796608ms","start":"2026-06-03T07:28:07.55147Z","end":"2026-06-03T07:28:07.661267Z","steps":["trace[999934433] 'agreement among raft nodes before linearized reading' (duration: 109.569995ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:07.661327Z","caller":"traceutil/trace.go:171","msg":"trace[1968285652] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2643; }","duration":"878.087267ms","start":"2026-06-03T07:28:06.783225Z","end":"2026-06-03T07:28:07.661312Z","steps":["trace[1968285652] 'agreement among raft nodes before linearized reading' (duration: 877.80867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:07.66142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:06.783212Z","time spent":"878.198743ms","remote":"127.0.0.1:51522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":6,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:28:07.661377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.615935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-03T07:28:07.661201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.774395ms","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-06-03T07:28:07.661546Z","caller":"traceutil/trace.go:171","msg":"trace[494615536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2643; }","duration":"926.804317ms","start":"2026-06-03T07:28:06.734687Z","end":"2026-06-03T07:28:07.661491Z","steps":["trace[494615536] 'agreement among raft nodes before linearized reading' (duration: 926.279455ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:07.661591Z","caller":"traceutil/trace.go:171","msg":"trace[143848053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2643; }","duration":"927.136977ms","start":"2026-06-03T07:28:06.734372Z","end":"2026-06-03T07:28:07.661509Z","steps":["trace[143848053] 'agreement among raft nodes before linearized reading' (duration: 926.68409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:07.661212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:06.730392Z","time spent":"930.68097ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-03T07:28:07.661656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:06.734353Z","time spent":"927.284206ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:28:07.661769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.880632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:28:07.66185Z","caller":"traceutil/trace.go:171","msg":"trace[564018989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2643; }","duration":"725.00679ms","start":"2026-06-03T07:28:06.936831Z","end":"2026-06-03T07:28:07.661838Z","steps":["trace[564018989] 'agreement among raft nodes before linearized reading' (duration: 724.068463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:07.661915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:06.93681Z","time spent":"725.095015ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T07:28:07.664421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:06.73468Z","time spent":"926.909514ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T07:28:22.807938Z","caller":"traceutil/trace.go:171","msg":"trace[2103363908] transaction","detail":"{read_only:false; response_revision:2724; number_of_response:1; }","duration":"128.712204ms","start":"2026-06-03T07:28:22.679199Z","end":"2026-06-03T07:28:22.807911Z","steps":["trace[2103363908] 'process raft request' (duration: 128.359053ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:23.155543Z","caller":"traceutil/trace.go:171","msg":"trace[1852570594] linearizableReadLoop","detail":"{readStateIndex:2829; appliedIndex:2828; }","duration":"306.57939ms","start":"2026-06-03T07:28:22.848936Z","end":"2026-06-03T07:28:23.155515Z","steps":["trace[1852570594] 'read index received' (duration: 306.440542ms)","trace[1852570594] 'applied index is now lower than readState.Index' (duration: 137.808µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:28:23.155735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.787623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b5818fecc41976\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-06-03T07:28:23.155778Z","caller":"traceutil/trace.go:171","msg":"trace[1154104684] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b5818fecc41976; range_end:; response_count:1; response_revision:2725; }","duration":"306.879708ms","start":"2026-06-03T07:28:22.848883Z","end":"2026-06-03T07:28:23.155763Z","steps":["trace[1154104684] 'agreement among raft nodes before linearized reading' (duration: 306.727239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:23.155818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:22.848865Z","time spent":"306.946102ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b5818fecc41976\" "} {"level":"info","ts":"2026-06-03T07:28:23.155857Z","caller":"traceutil/trace.go:171","msg":"trace[358121624] transaction","detail":"{read_only:false; response_revision:2725; number_of_response:1; }","duration":"342.392284ms","start":"2026-06-03T07:28:22.8134Z","end":"2026-06-03T07:28:23.155792Z","steps":["trace[358121624] 'process raft request' (duration: 341.955947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:23.1561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:22.81338Z","time spent":"342.584335ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:28:24.60953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016677804s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:28:24.609682Z","caller":"traceutil/trace.go:171","msg":"trace[1142185127] linearizableReadLoop","detail":"{readStateIndex:2831; appliedIndex:2830; }","duration":"1.387189665s","start":"2026-06-03T07:28:23.222445Z","end":"2026-06-03T07:28:24.609635Z","steps":["trace[1142185127] 'read index received' (duration: 370.18231ms)","trace[1142185127] 'applied index is now lower than readState.Index' (duration: 1.017006235s)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:28:24.609815Z","caller":"traceutil/trace.go:171","msg":"trace[784446613] transaction","detail":"{read_only:false; response_revision:2727; number_of_response:1; }","duration":"1.4496563s","start":"2026-06-03T07:28:23.160088Z","end":"2026-06-03T07:28:24.609744Z","steps":["trace[784446613] 'process raft request' (duration: 432.641385ms)","trace[784446613] 'compare' (duration: 1.015760579s)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:28:24.609866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.160066Z","time spent":"1.449776877s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:28:24.610735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3882509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:28:24.610851Z","caller":"traceutil/trace.go:171","msg":"trace[1038412473] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2727; }","duration":"1.388428781s","start":"2026-06-03T07:28:23.22239Z","end":"2026-06-03T07:28:24.610819Z","steps":["trace[1038412473] 'agreement among raft nodes before linearized reading' (duration: 1.388140143s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:24.610923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.22237Z","time spent":"1.388538377s","remote":"127.0.0.1:41528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:28:24.999333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.118066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:28:24.999463Z","caller":"traceutil/trace.go:171","msg":"trace[86278671] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"1.101015585s","start":"2026-06-03T07:28:23.898424Z","end":"2026-06-03T07:28:24.99944Z","steps":["trace[86278671] 'process raft request' (duration: 875.703116ms)","trace[86278671] 'compare' (duration: 224.758845ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:28:24.999533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.898398Z","time spent":"1.10110659s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:28:25.033511Z","caller":"traceutil/trace.go:171","msg":"trace[1833811468] transaction","detail":"{read_only:false; response_revision:2730; number_of_response:1; }","duration":"995.028636ms","start":"2026-06-03T07:28:24.038451Z","end":"2026-06-03T07:28:25.03348Z","steps":["trace[1833811468] 'process raft request' (duration: 994.950522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.033684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:24.038434Z","time spent":"995.161694ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:28:25.033718Z","caller":"traceutil/trace.go:171","msg":"trace[595525486] transaction","detail":"{read_only:false; response_revision:2729; number_of_response:1; }","duration":"995.649244ms","start":"2026-06-03T07:28:24.038022Z","end":"2026-06-03T07:28:25.033671Z","steps":["trace[595525486] 'process raft request' (duration: 995.234719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.033868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:24.038005Z","time spent":"995.784212ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:28:25.034075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.774369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-03T07:28:25.03407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096858175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:28:25.0341Z","caller":"traceutil/trace.go:171","msg":"trace[687964711] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:2731; }","duration":"679.801211ms","start":"2026-06-03T07:28:24.354292Z","end":"2026-06-03T07:28:25.034093Z","steps":["trace[687964711] 'agreement among raft nodes before linearized reading' (duration: 679.722786ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:25.034117Z","caller":"traceutil/trace.go:171","msg":"trace[1328400482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2731; }","duration":"1.096936679s","start":"2026-06-03T07:28:23.937168Z","end":"2026-06-03T07:28:25.034104Z","steps":["trace[1328400482] 'agreement among raft nodes before linearized reading' (duration: 1.096793591s)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:25.034149Z","caller":"traceutil/trace.go:171","msg":"trace[664005765] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"982.855896ms","start":"2026-06-03T07:28:24.051288Z","end":"2026-06-03T07:28:25.034144Z","steps":["trace[664005765] 'process raft request' (duration: 982.154913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.034158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.93715Z","time spent":"1.097001563s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T07:28:25.034177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:24.051275Z","time spent":"982.886688ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:28:25.034301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.193781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-06-03T07:28:25.034321Z","caller":"traceutil/trace.go:171","msg":"trace[95486934] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2731; }","duration":"487.261225ms","start":"2026-06-03T07:28:24.547052Z","end":"2026-06-03T07:28:25.034313Z","steps":["trace[95486934] 'agreement among raft nodes before linearized reading' (duration: 487.17663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.034357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:24.547023Z","time spent":"487.329679ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-03T07:28:25.034448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291213943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-03T07:28:25.034466Z","caller":"traceutil/trace.go:171","msg":"trace[523317066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2731; }","duration":"1.291259875s","start":"2026-06-03T07:28:23.743199Z","end":"2026-06-03T07:28:25.034459Z","steps":["trace[523317066] 'agreement among raft nodes before linearized reading' (duration: 1.291191441s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.034478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.743183Z","time spent":"1.291291997s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T07:28:25.03413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:24.354275Z","time spent":"679.850294ms","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-03T07:28:25.034604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.237824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:28:25.034662Z","caller":"traceutil/trace.go:171","msg":"trace[1864033633] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2731; }","duration":"913.333339ms","start":"2026-06-03T07:28:24.121309Z","end":"2026-06-03T07:28:25.034643Z","steps":["trace[1864033633] 'agreement among raft nodes before linearized reading' (duration: 913.243284ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.034726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262596404s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-06-03T07:28:25.034764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001225182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"warn","ts":"2026-06-03T07:28:25.03476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:24.121288Z","time spent":"913.454106ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-03T07:28:25.034792Z","caller":"traceutil/trace.go:171","msg":"trace[1541405701] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2731; }","duration":"1.001318489s","start":"2026-06-03T07:28:24.033464Z","end":"2026-06-03T07:28:25.034783Z","steps":["trace[1541405701] 'agreement among raft nodes before linearized reading' (duration: 1.001225743s)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:28:25.034792Z","caller":"traceutil/trace.go:171","msg":"trace[1606176379] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2731; }","duration":"1.26268468s","start":"2026-06-03T07:28:23.772085Z","end":"2026-06-03T07:28:25.03477Z","steps":["trace[1606176379] 'agreement among raft nodes before linearized reading' (duration: 1.262472507s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.034839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.77207Z","time spent":"1.262756924s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-03T07:28:25.034809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:24.033429Z","time spent":"1.001375652s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":550,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-03T07:28:25.034981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263644888s","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-06-03T07:28:25.035003Z","caller":"traceutil/trace.go:171","msg":"trace[70883248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2731; }","duration":"1.263699762s","start":"2026-06-03T07:28:23.771298Z","end":"2026-06-03T07:28:25.034998Z","steps":["trace[70883248] 'agreement among raft nodes before linearized reading' (duration: 1.263614336s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.035017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.771276Z","time spent":"1.263738564s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T07:28:25.035036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3561857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:28:25.035076Z","caller":"traceutil/trace.go:171","msg":"trace[515976412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2731; }","duration":"1.356251934s","start":"2026-06-03T07:28:23.678812Z","end":"2026-06-03T07:28:25.035064Z","steps":["trace[515976412] 'agreement among raft nodes before linearized reading' (duration: 1.35618581s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.03513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.678798Z","time spent":"1.356322488s","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T07:28:25.035241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269105539s","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-06-03T07:28:25.035259Z","caller":"traceutil/trace.go:171","msg":"trace[1790305703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2731; }","duration":"1.269158372s","start":"2026-06-03T07:28:23.766095Z","end":"2026-06-03T07:28:25.035254Z","steps":["trace[1790305703] 'agreement among raft nodes before linearized reading' (duration: 1.268433648s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.035196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268225085s","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-06-03T07:28:25.035329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484157986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-03T07:28:25.035346Z","caller":"traceutil/trace.go:171","msg":"trace[1649146814] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2731; }","duration":"1.484210209s","start":"2026-06-03T07:28:23.551132Z","end":"2026-06-03T07:28:25.035342Z","steps":["trace[1649146814] 'agreement among raft nodes before linearized reading' (duration: 1.484159716s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.035375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.551113Z","time spent":"1.484257461s","remote":"127.0.0.1:51212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1391,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-06-03T07:28:25.035273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.766068Z","time spent":"1.269201455s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-03T07:28:25.035743Z","caller":"traceutil/trace.go:171","msg":"trace[663487169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2731; }","duration":"1.268657683s","start":"2026-06-03T07:28:23.766651Z","end":"2026-06-03T07:28:25.035308Z","steps":["trace[663487169] 'agreement among raft nodes before linearized reading' (duration: 1.267370114s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:28:25.035824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:28:23.766626Z","time spent":"1.269178034s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-03T07:28:25.036717Z","caller":"traceutil/trace.go:171","msg":"trace[1222151183] linearizableReadLoop","detail":"{readStateIndex:2835; appliedIndex:2831; }","duration":"424.18721ms","start":"2026-06-03T07:28:24.609702Z","end":"2026-06-03T07:28:25.033889Z","steps":["trace[1222151183] 'read index received' (duration: 164.447728ms)","trace[1222151183] 'applied index is now lower than readState.Index' (duration: 259.737602ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:28:25.037781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.570429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T07:28:25.040242Z","caller":"traceutil/trace.go:171","msg":"trace[586556370] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2731; }","duration":"171.098404ms","start":"2026-06-03T07:28:24.869114Z","end":"2026-06-03T07:28:25.040212Z","steps":["trace[586556370] 'agreement among raft nodes before linearized reading' (duration: 168.549149ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:30:45.714185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.453774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:30:45.714547Z","caller":"traceutil/trace.go:171","msg":"trace[269272618] transaction","detail":"{read_only:false; response_revision:3559; number_of_response:1; }","duration":"124.667998ms","start":"2026-06-03T07:30:45.589854Z","end":"2026-06-03T07:30:45.714522Z","steps":["trace[269272618] 'process raft request' (duration: 124.461714ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:30:45.714568Z","caller":"traceutil/trace.go:171","msg":"trace[200600959] transaction","detail":"{read_only:false; response_revision:3560; number_of_response:1; }","duration":"110.900635ms","start":"2026-06-03T07:30:45.603625Z","end":"2026-06-03T07:30:45.714526Z","steps":["trace[200600959] 'process raft request' (duration: 110.775283ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:30:45.71463Z","caller":"traceutil/trace.go:171","msg":"trace[361617852] transaction","detail":"{read_only:false; response_revision:3558; number_of_response:1; }","duration":"175.310492ms","start":"2026-06-03T07:30:45.539312Z","end":"2026-06-03T07:30:45.714622Z","steps":["trace[361617852] 'process raft request' (duration: 57.366996ms)","trace[361617852] 'compare' (duration: 117.319391ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:00.294796Z","caller":"traceutil/trace.go:171","msg":"trace[259736193] linearizableReadLoop","detail":"{readStateIndex:3779; appliedIndex:3775; }","duration":"154.637584ms","start":"2026-06-03T07:31:00.140125Z","end":"2026-06-03T07:31:00.294762Z","steps":["trace[259736193] 'read index received' (duration: 14.806797ms)","trace[259736193] 'applied index is now lower than readState.Index' (duration: 139.829487ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:31:00.294968Z","caller":"traceutil/trace.go:171","msg":"trace[569415295] transaction","detail":"{read_only:false; response_revision:3638; number_of_response:1; }","duration":"164.040219ms","start":"2026-06-03T07:31:00.130897Z","end":"2026-06-03T07:31:00.294937Z","steps":["trace[569415295] 'process raft request' (duration: 163.671399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:00.295195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.056205ms","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-06-03T07:31:00.295266Z","caller":"traceutil/trace.go:171","msg":"trace[769181213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3639; }","duration":"155.163738ms","start":"2026-06-03T07:31:00.140089Z","end":"2026-06-03T07:31:00.295252Z","steps":["trace[769181213] 'agreement among raft nodes before linearized reading' (duration: 154.988113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:00.295448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.039773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:31:00.2955Z","caller":"traceutil/trace.go:171","msg":"trace[688934821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:3639; }","duration":"112.161826ms","start":"2026-06-03T07:31:00.183325Z","end":"2026-06-03T07:31:00.295487Z","steps":["trace[688934821] 'agreement among raft nodes before linearized reading' (duration: 111.858578ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:00.294913Z","caller":"traceutil/trace.go:171","msg":"trace[1571625025] transaction","detail":"{read_only:false; response_revision:3637; number_of_response:1; }","duration":"164.10873ms","start":"2026-06-03T07:31:00.130778Z","end":"2026-06-03T07:31:00.294887Z","steps":["trace[1571625025] 'process raft request' (duration: 163.437683ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:31:00.295066Z","caller":"traceutil/trace.go:171","msg":"trace[712174879] transaction","detail":"{read_only:false; response_revision:3639; number_of_response:1; }","duration":"163.944606ms","start":"2026-06-03T07:31:00.131106Z","end":"2026-06-03T07:31:00.295051Z","steps":["trace[712174879] 'process raft request' (duration: 163.597147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:31:02.278584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.928791ms","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-06-03T07:31:02.278668Z","caller":"traceutil/trace.go:171","msg":"trace[247204432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3645; }","duration":"119.053254ms","start":"2026-06-03T07:31:02.159596Z","end":"2026-06-03T07:31:02.27865Z","steps":["trace[247204432] 'range keys from in-memory index tree' (duration: 118.771667ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:15.010693Z","caller":"traceutil/trace.go:171","msg":"trace[1133839662] linearizableReadLoop","detail":"{readStateIndex:4713; appliedIndex:4712; }","duration":"155.717372ms","start":"2026-06-03T07:32:14.85495Z","end":"2026-06-03T07:32:15.010668Z","steps":["trace[1133839662] 'read index received' (duration: 142.776103ms)","trace[1133839662] 'applied index is now lower than readState.Index' (duration: 12.939709ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:15.01072Z","caller":"traceutil/trace.go:171","msg":"trace[2017030590] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"169.732181ms","start":"2026-06-03T07:32:14.840951Z","end":"2026-06-03T07:32:15.010683Z","steps":["trace[2017030590] 'process raft request' (duration: 156.837094ms)","trace[2017030590] 'compare' (duration: 12.312795ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:15.010958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.996682ms","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-06-03T07:32:15.010989Z","caller":"traceutil/trace.go:171","msg":"trace[241180229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4537; }","duration":"156.071505ms","start":"2026-06-03T07:32:14.854909Z","end":"2026-06-03T07:32:15.010981Z","steps":["trace[241180229] 'agreement among raft nodes before linearized reading' (duration: 155.895398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:15.011333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.123673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:32:15.011358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.344438ms","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-06-03T07:32:15.011421Z","caller":"traceutil/trace.go:171","msg":"trace[1153491672] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4537; }","duration":"149.254818ms","start":"2026-06-03T07:32:14.862148Z","end":"2026-06-03T07:32:15.011403Z","steps":["trace[1153491672] 'agreement among raft nodes before linearized reading' (duration: 149.081132ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:15.011446Z","caller":"traceutil/trace.go:171","msg":"trace[1316876010] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4537; }","duration":"155.460103ms","start":"2026-06-03T07:32:14.855961Z","end":"2026-06-03T07:32:15.011421Z","steps":["trace[1316876010] 'agreement among raft nodes before linearized reading' (duration: 155.194953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:15.011515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.677402ms","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-06-03T07:32:15.011572Z","caller":"traceutil/trace.go:171","msg":"trace[2012683842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4537; }","duration":"154.764066ms","start":"2026-06-03T07:32:14.856795Z","end":"2026-06-03T07:32:15.011559Z","steps":["trace[2012683842] 'agreement among raft nodes before linearized reading' (duration: 154.575659ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:17.24347Z","caller":"traceutil/trace.go:171","msg":"trace[1573936418] linearizableReadLoop","detail":"{readStateIndex:4755; appliedIndex:4753; }","duration":"192.265888ms","start":"2026-06-03T07:32:17.051175Z","end":"2026-06-03T07:32:17.243441Z","steps":["trace[1573936418] 'read index received' (duration: 40.13439ms)","trace[1573936418] 'applied index is now lower than readState.Index' (duration: 152.130228ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:17.243691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.501747ms","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-06-03T07:32:17.243725Z","caller":"traceutil/trace.go:171","msg":"trace[1295302955] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4578; }","duration":"192.604141ms","start":"2026-06-03T07:32:17.05111Z","end":"2026-06-03T07:32:17.243714Z","steps":["trace[1295302955] 'agreement among raft nodes before linearized reading' (duration: 192.442364ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:17.244474Z","caller":"traceutil/trace.go:171","msg":"trace[1358566629] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"199.92565ms","start":"2026-06-03T07:32:17.04452Z","end":"2026-06-03T07:32:17.244445Z","steps":["trace[1358566629] 'process raft request' (duration: 198.752746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:17.286905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.646652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:17.286983Z","caller":"traceutil/trace.go:171","msg":"trace[394460546] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4579; }","duration":"182.774696ms","start":"2026-06-03T07:32:17.104188Z","end":"2026-06-03T07:32:17.286962Z","steps":["trace[394460546] 'agreement among raft nodes before linearized reading' (duration: 182.650171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:17.287126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.163246ms","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-06-03T07:32:17.287216Z","caller":"traceutil/trace.go:171","msg":"trace[1228654429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4579; }","duration":"234.29282ms","start":"2026-06-03T07:32:17.052901Z","end":"2026-06-03T07:32:17.287194Z","steps":["trace[1228654429] 'agreement among raft nodes before linearized reading' (duration: 234.02247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:17.286933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.02925ms","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-06-03T07:32:17.2877Z","caller":"traceutil/trace.go:171","msg":"trace[1778913058] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"162.256815ms","start":"2026-06-03T07:32:17.125242Z","end":"2026-06-03T07:32:17.287499Z","steps":["trace[1778913058] 'process raft request' (duration: 161.197684ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:17.28836Z","caller":"traceutil/trace.go:171","msg":"trace[833488509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4579; }","duration":"235.471835ms","start":"2026-06-03T07:32:17.052872Z","end":"2026-06-03T07:32:17.288344Z","steps":["trace[833488509] 'agreement among raft nodes before linearized reading' (duration: 233.937986ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:18.040742Z","caller":"traceutil/trace.go:171","msg":"trace[851598052] linearizableReadLoop","detail":"{readStateIndex:4765; appliedIndex:4764; }","duration":"103.020691ms","start":"2026-06-03T07:32:17.937697Z","end":"2026-06-03T07:32:18.040718Z","steps":["trace[851598052] 'read index received' (duration: 102.803123ms)","trace[851598052] 'applied index is now lower than readState.Index' (duration: 216.648µs)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:18.040931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.206698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:18.040978Z","caller":"traceutil/trace.go:171","msg":"trace[373972631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4588; }","duration":"103.303011ms","start":"2026-06-03T07:32:17.93766Z","end":"2026-06-03T07:32:18.040963Z","steps":["trace[373972631] 'agreement among raft nodes before linearized reading' (duration: 103.170786ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:18.041459Z","caller":"traceutil/trace.go:171","msg":"trace[1342681464] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"133.843306ms","start":"2026-06-03T07:32:17.907595Z","end":"2026-06-03T07:32:18.041439Z","steps":["trace[1342681464] 'process raft request' (duration: 132.977972ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:20.164424Z","caller":"traceutil/trace.go:171","msg":"trace[1248592565] transaction","detail":"{read_only:false; response_revision:4634; number_of_response:1; }","duration":"118.302759ms","start":"2026-06-03T07:32:20.046101Z","end":"2026-06-03T07:32:20.164404Z","steps":["trace[1248592565] 'process raft request' (duration: 118.164144ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:20.241554Z","caller":"traceutil/trace.go:171","msg":"trace[1092583015] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"147.211311ms","start":"2026-06-03T07:32:20.094307Z","end":"2026-06-03T07:32:20.241519Z","steps":["trace[1092583015] 'process raft request' (duration: 146.973222ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:20.24173Z","caller":"traceutil/trace.go:171","msg":"trace[1099159562] transaction","detail":"{read_only:false; response_revision:4636; number_of_response:1; }","duration":"124.684595ms","start":"2026-06-03T07:32:20.117022Z","end":"2026-06-03T07:32:20.241706Z","steps":["trace[1099159562] 'process raft request' (duration: 124.423855ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:20.574486Z","caller":"traceutil/trace.go:171","msg":"trace[1477431263] linearizableReadLoop","detail":"{readStateIndex:4818; appliedIndex:4817; }","duration":"293.239266ms","start":"2026-06-03T07:32:20.281227Z","end":"2026-06-03T07:32:20.574466Z","steps":["trace[1477431263] 'read index received' (duration: 293.058579ms)","trace[1477431263] 'applied index is now lower than readState.Index' (duration: 179.676µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:20.574601Z","caller":"traceutil/trace.go:171","msg":"trace[169028727] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"327.601707ms","start":"2026-06-03T07:32:20.24699Z","end":"2026-06-03T07:32:20.574592Z","steps":["trace[169028727] 'process raft request' (duration: 327.348458ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:20.574681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:20.246975Z","time spent":"327.65155ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-03T07:32:20.57505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.004482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-03T07:32:20.575077Z","caller":"traceutil/trace.go:171","msg":"trace[482193762] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4639; }","duration":"329.054954ms","start":"2026-06-03T07:32:20.246016Z","end":"2026-06-03T07:32:20.575071Z","steps":["trace[482193762] 'agreement among raft nodes before linearized reading' (duration: 328.95057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:20.575092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:20.246006Z","time spent":"329.082904ms","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1146,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-06-03T07:32:20.575151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.910006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-06-03T07:32:20.575276Z","caller":"traceutil/trace.go:171","msg":"trace[2070916448] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4639; }","duration":"291.135425ms","start":"2026-06-03T07:32:20.284122Z","end":"2026-06-03T07:32:20.575257Z","steps":["trace[2070916448] 'agreement among raft nodes before linearized reading' (duration: 290.74646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:20.575312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.119464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6fjh9-tls\" ","response":"range_response_count:1 size:2130"} {"level":"info","ts":"2026-06-03T07:32:20.575472Z","caller":"traceutil/trace.go:171","msg":"trace[2081282169] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6fjh9-tls; range_end:; response_count:1; response_revision:4639; }","duration":"324.305141ms","start":"2026-06-03T07:32:20.25116Z","end":"2026-06-03T07:32:20.575465Z","steps":["trace[2081282169] 'agreement among raft nodes before linearized reading' (duration: 324.087372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:20.575536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:20.251149Z","time spent":"324.338192ms","remote":"127.0.0.1:49564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":2152,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-6fjh9-tls\" "} {"level":"info","ts":"2026-06-03T07:32:21.702529Z","caller":"traceutil/trace.go:171","msg":"trace[398294863] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4845; }","duration":"406.662392ms","start":"2026-06-03T07:32:21.295844Z","end":"2026-06-03T07:32:21.702506Z","steps":["trace[398294863] 'read index received' (duration: 406.401482ms)","trace[398294863] 'applied index is now lower than readState.Index' (duration: 260.02µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:21.702639Z","caller":"traceutil/trace.go:171","msg":"trace[1521367645] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"407.024976ms","start":"2026-06-03T07:32:21.295603Z","end":"2026-06-03T07:32:21.702628Z","steps":["trace[1521367645] 'process raft request' (duration: 406.703644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:21.702748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:21.29558Z","time spent":"407.084159ms","remote":"127.0.0.1:51200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4933,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:21.702889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.248429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:32:21.702973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.77282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-06-03T07:32:21.703027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.155122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-06-03T07:32:21.703063Z","caller":"traceutil/trace.go:171","msg":"trace[624160829] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4665; }","duration":"383.948277ms","start":"2026-06-03T07:32:21.319092Z","end":"2026-06-03T07:32:21.70304Z","steps":["trace[624160829] 'agreement among raft nodes before linearized reading' (duration: 383.585563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:21.703121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:21.319076Z","time spent":"384.03594ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-06-03T07:32:21.703133Z","caller":"traceutil/trace.go:171","msg":"trace[932917301] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4665; }","duration":"407.295597ms","start":"2026-06-03T07:32:21.295803Z","end":"2026-06-03T07:32:21.703099Z","steps":["trace[932917301] 'agreement among raft nodes before linearized reading' (duration: 407.006086ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:21.703052Z","caller":"traceutil/trace.go:171","msg":"trace[516803770] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:4665; }","duration":"175.458997ms","start":"2026-06-03T07:32:21.527542Z","end":"2026-06-03T07:32:21.703001Z","steps":["trace[516803770] 'agreement among raft nodes before linearized reading' (duration: 175.193876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:21.703204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:21.295787Z","time spent":"407.402161ms","remote":"127.0.0.1:51110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3528,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-06-03T07:32:21.703348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.175278ms","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-06-03T07:32:21.703496Z","caller":"traceutil/trace.go:171","msg":"trace[1205866767] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4665; }","duration":"369.285361ms","start":"2026-06-03T07:32:21.334142Z","end":"2026-06-03T07:32:21.703427Z","steps":["trace[1205866767] 'agreement among raft nodes before linearized reading' (duration: 369.111374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:21.703555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:21.334131Z","time spent":"369.405416ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:32:21.703818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.699051ms","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-06-03T07:32:21.704237Z","caller":"traceutil/trace.go:171","msg":"trace[318151210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4665; }","duration":"367.141799ms","start":"2026-06-03T07:32:21.337079Z","end":"2026-06-03T07:32:21.70422Z","steps":["trace[318151210] 'agreement among raft nodes before linearized reading' (duration: 366.619438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:21.704308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:21.337067Z","time spent":"367.224191ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-03T07:32:27.399293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262227529025065958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T07:32:27.899714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262227529025065958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-03T07:32:27.945905Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075879531s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-03T07:32:27.946602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082279451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-06-03T07:32:27.946704Z","caller":"traceutil/trace.go:171","msg":"trace[153031250] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4767; }","duration":"1.082417747s","start":"2026-06-03T07:32:26.864264Z","end":"2026-06-03T07:32:27.946682Z","steps":["trace[153031250] 'range keys from in-memory index tree' (duration: 1.082103344s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:27.946747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:26.864253Z","time spent":"1.082484239s","remote":"127.0.0.1:51200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12997,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-06-03T07:32:27.946763Z","caller":"traceutil/trace.go:171","msg":"trace[2134281048] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"1.076825077s","start":"2026-06-03T07:32:26.869923Z","end":"2026-06-03T07:32:27.946748Z","steps":["trace[2134281048] 'process raft request' (duration: 1.07636927s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:27.946849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:26.869911Z","time spent":"1.076892471s","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-03T07:32:28.126043Z","caller":"traceutil/trace.go:171","msg":"trace[319632848] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4950; }","duration":"1.227775202s","start":"2026-06-03T07:32:26.898243Z","end":"2026-06-03T07:32:28.126018Z","steps":["trace[319632848] 'read index received' (duration: 1.048406487s)","trace[319632848] 'applied index is now lower than readState.Index' (duration: 179.367315ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:28.126171Z","caller":"traceutil/trace.go:171","msg":"trace[1746057366] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"864.785631ms","start":"2026-06-03T07:32:27.261349Z","end":"2026-06-03T07:32:28.126135Z","steps":["trace[1746057366] 'process raft request' (duration: 864.463568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.126259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22799765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-03T07:32:28.126296Z","caller":"traceutil/trace.go:171","msg":"trace[2077144293] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4769; }","duration":"1.228163508s","start":"2026-06-03T07:32:26.89812Z","end":"2026-06-03T07:32:28.126284Z","steps":["trace[2077144293] 'agreement among raft nodes before linearized reading' (duration: 1.228010021s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.126341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:26.898096Z","time spent":"1.228235529s","remote":"127.0.0.1:51182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-06-03T07:32:28.126381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:27.261315Z","time spent":"864.960317ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:28.176118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.12923ms","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-06-03T07:32:28.176189Z","caller":"traceutil/trace.go:171","msg":"trace[1683781117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4770; }","duration":"435.232783ms","start":"2026-06-03T07:32:27.740944Z","end":"2026-06-03T07:32:28.176177Z","steps":["trace[1683781117] 'agreement among raft nodes before linearized reading' (duration: 435.007894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.176189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.466603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:28.176282Z","caller":"traceutil/trace.go:171","msg":"trace[1450149367] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4770; }","duration":"219.596178ms","start":"2026-06-03T07:32:27.95666Z","end":"2026-06-03T07:32:28.176256Z","steps":["trace[1450149367] 'agreement among raft nodes before linearized reading' (duration: 219.37349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.176296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:27.740933Z","time spent":"435.275285ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-03T07:32:28.176342Z","caller":"traceutil/trace.go:171","msg":"trace[633194542] transaction","detail":"{read_only:false; response_revision:4770; number_of_response:1; }","duration":"216.350581ms","start":"2026-06-03T07:32:27.95996Z","end":"2026-06-03T07:32:28.176311Z","steps":["trace[633194542] 'process raft request' (duration: 215.815559ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.176556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.403205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-86cd9d7c4d-t7rs7.18b581cd654a82da\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-06-03T07:32:28.176584Z","caller":"traceutil/trace.go:171","msg":"trace[1147448516] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-86cd9d7c4d-t7rs7.18b581cd654a82da; range_end:; response_count:1; response_revision:4770; }","duration":"226.464168ms","start":"2026-06-03T07:32:27.950112Z","end":"2026-06-03T07:32:28.176577Z","steps":["trace[1147448516] 'agreement among raft nodes before linearized reading' (duration: 226.389715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.176653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.251478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-411ef038-bae5-46b6-a3a8-c52f6c05a308\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-06-03T07:32:28.176723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.377489ms","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-06-03T07:32:28.176714Z","caller":"traceutil/trace.go:171","msg":"trace[303103628] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-411ef038-bae5-46b6-a3a8-c52f6c05a308; range_end:; response_count:1; response_revision:4770; }","duration":"912.335811ms","start":"2026-06-03T07:32:27.264361Z","end":"2026-06-03T07:32:28.176697Z","steps":["trace[303103628] 'agreement among raft nodes before linearized reading' (duration: 912.219766ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.17675Z","caller":"traceutil/trace.go:171","msg":"trace[983703511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4770; }","duration":"435.444603ms","start":"2026-06-03T07:32:27.741299Z","end":"2026-06-03T07:32:28.176744Z","steps":["trace[983703511] 'agreement among raft nodes before linearized reading' (duration: 435.37741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.176759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:27.264345Z","time spent":"912.405984ms","remote":"127.0.0.1:51200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-411ef038-bae5-46b6-a3a8-c52f6c05a308\" "} {"level":"warn","ts":"2026-06-03T07:32:28.176773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:27.741279Z","time spent":"435.490134ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-03T07:32:28.176809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237723302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:28.176865Z","caller":"traceutil/trace.go:171","msg":"trace[434770141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4770; }","duration":"1.237847107s","start":"2026-06-03T07:32:26.939004Z","end":"2026-06-03T07:32:28.176851Z","steps":["trace[434770141] 'agreement among raft nodes before linearized reading' (duration: 1.237753403s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.176904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:26.938983Z","time spent":"1.23791423s","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T07:32:28.176133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.615323ms","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-06-03T07:32:28.177006Z","caller":"traceutil/trace.go:171","msg":"trace[1705217683] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4770; }","duration":"442.54067ms","start":"2026-06-03T07:32:27.734454Z","end":"2026-06-03T07:32:28.176995Z","steps":["trace[1705217683] 'agreement among raft nodes before linearized reading' (duration: 441.51433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.177048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:27.734425Z","time spent":"442.613003ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-03T07:32:28.176646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264300011s","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-06-03T07:32:28.177337Z","caller":"traceutil/trace.go:171","msg":"trace[1201878846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4770; }","duration":"1.265486637s","start":"2026-06-03T07:32:26.911812Z","end":"2026-06-03T07:32:28.177299Z","steps":["trace[1201878846] 'agreement among raft nodes before linearized reading' (duration: 1.264177066s)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.177455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:26.911791Z","time spent":"1.265621293s","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-03T07:32:28.290371Z","caller":"traceutil/trace.go:171","msg":"trace[1360615476] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"110.536426ms","start":"2026-06-03T07:32:28.179806Z","end":"2026-06-03T07:32:28.290342Z","steps":["trace[1360615476] 'process raft request' (duration: 18.552149ms)","trace[1360615476] 'compare' (duration: 91.862522ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:28.340648Z","caller":"traceutil/trace.go:171","msg":"trace[236386861] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"158.315634ms","start":"2026-06-03T07:32:28.182307Z","end":"2026-06-03T07:32:28.340623Z","steps":["trace[236386861] 'process raft request' (duration: 158.074675ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.340941Z","caller":"traceutil/trace.go:171","msg":"trace[1659764940] linearizableReadLoop","detail":"{readStateIndex:4960; appliedIndex:4953; }","duration":"155.779255ms","start":"2026-06-03T07:32:28.185138Z","end":"2026-06-03T07:32:28.340917Z","steps":["trace[1659764940] 'read index received' (duration: 13.20418ms)","trace[1659764940] 'applied index is now lower than readState.Index' (duration: 142.574125ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:28.341Z","caller":"traceutil/trace.go:171","msg":"trace[304370753] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"155.229923ms","start":"2026-06-03T07:32:28.185752Z","end":"2026-06-03T07:32:28.340982Z","steps":["trace[304370753] 'process raft request' (duration: 154.968333ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.341054Z","caller":"traceutil/trace.go:171","msg":"trace[1822885449] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"158.357306ms","start":"2026-06-03T07:32:28.182685Z","end":"2026-06-03T07:32:28.341043Z","steps":["trace[1822885449] 'process raft request' (duration: 157.910148ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.341124Z","caller":"traceutil/trace.go:171","msg":"trace[1520480108] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"157.520783ms","start":"2026-06-03T07:32:28.183595Z","end":"2026-06-03T07:32:28.341115Z","steps":["trace[1520480108] 'process raft request' (duration: 157.050774ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.341159Z","caller":"traceutil/trace.go:171","msg":"trace[2036163065] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"154.761375ms","start":"2026-06-03T07:32:28.186385Z","end":"2026-06-03T07:32:28.341147Z","steps":["trace[2036163065] 'process raft request' (duration: 154.368529ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.341206Z","caller":"traceutil/trace.go:171","msg":"trace[507814006] transaction","detail":"{read_only:false; response_revision:4777; number_of_response:1; }","duration":"157.43769ms","start":"2026-06-03T07:32:28.18376Z","end":"2026-06-03T07:32:28.341198Z","steps":["trace[507814006] 'process raft request' (duration: 156.92655ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.340717Z","caller":"traceutil/trace.go:171","msg":"trace[1369775310] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"158.142678ms","start":"2026-06-03T07:32:28.182539Z","end":"2026-06-03T07:32:28.340682Z","steps":["trace[1369775310] 'process raft request' (duration: 158.022253ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.340783Z","caller":"traceutil/trace.go:171","msg":"trace[1247423703] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"158.382106ms","start":"2026-06-03T07:32:28.182391Z","end":"2026-06-03T07:32:28.340774Z","steps":["trace[1247423703] 'process raft request' (duration: 158.131776ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.341412Z","caller":"traceutil/trace.go:171","msg":"trace[744842595] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"154.845068ms","start":"2026-06-03T07:32:28.186555Z","end":"2026-06-03T07:32:28.341401Z","steps":["trace[744842595] 'process raft request' (duration: 154.232354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.341637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.479732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13296"} {"level":"info","ts":"2026-06-03T07:32:28.341694Z","caller":"traceutil/trace.go:171","msg":"trace[1694170561] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4780; }","duration":"156.570666ms","start":"2026-06-03T07:32:28.185112Z","end":"2026-06-03T07:32:28.341682Z","steps":["trace[1694170561] 'agreement among raft nodes before linearized reading' (duration: 156.41287ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.506914Z","caller":"traceutil/trace.go:171","msg":"trace[1646519212] linearizableReadLoop","detail":"{readStateIndex:4965; appliedIndex:4963; }","duration":"165.885402ms","start":"2026-06-03T07:32:28.341004Z","end":"2026-06-03T07:32:28.50689Z","steps":["trace[1646519212] 'read index received' (duration: 159.72354ms)","trace[1646519212] 'applied index is now lower than readState.Index' (duration: 6.160962ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:28.506931Z","caller":"traceutil/trace.go:171","msg":"trace[1738978503] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"231.888226ms","start":"2026-06-03T07:32:28.275011Z","end":"2026-06-03T07:32:28.506899Z","steps":["trace[1738978503] 'process raft request' (duration: 225.776026ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:28.506929Z","caller":"traceutil/trace.go:171","msg":"trace[25640088] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"185.864778ms","start":"2026-06-03T07:32:28.321038Z","end":"2026-06-03T07:32:28.506903Z","steps":["trace[25640088] 'process raft request' (duration: 185.752623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.5071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.957833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-06-03T07:32:28.507196Z","caller":"traceutil/trace.go:171","msg":"trace[251966392] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4782; }","duration":"256.108849ms","start":"2026-06-03T07:32:28.251067Z","end":"2026-06-03T07:32:28.507176Z","steps":["trace[251966392] 'agreement among raft nodes before linearized reading' (duration: 255.839569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.507045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.272814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:28.507582Z","caller":"traceutil/trace.go:171","msg":"trace[454941649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4782; }","duration":"317.842036ms","start":"2026-06-03T07:32:28.189724Z","end":"2026-06-03T07:32:28.507566Z","steps":["trace[454941649] 'agreement among raft nodes before linearized reading' (duration: 317.260393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.508624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:28.189714Z","time spent":"317.92464ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-03T07:32:28.953105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.902565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:32:28.953179Z","caller":"traceutil/trace.go:171","msg":"trace[2041852881] linearizableReadLoop","detail":"{readStateIndex:4966; appliedIndex:4965; }","duration":"429.422233ms","start":"2026-06-03T07:32:28.523745Z","end":"2026-06-03T07:32:28.953167Z","steps":["trace[2041852881] 'read index received' (duration: 326.501206ms)","trace[2041852881] 'applied index is now lower than readState.Index' (duration: 102.920287ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:28.953233Z","caller":"traceutil/trace.go:171","msg":"trace[1619699098] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"432.353408ms","start":"2026-06-03T07:32:28.520872Z","end":"2026-06-03T07:32:28.953226Z","steps":["trace[1619699098] 'process raft request' (duration: 329.244374ms)","trace[1619699098] 'compare' (duration: 102.811722ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:28.953269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:28.520862Z","time spent":"432.387359ms","remote":"127.0.0.1:51080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:28.953347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.929897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-03T07:32:28.953407Z","caller":"traceutil/trace.go:171","msg":"trace[1825880507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4783; }","duration":"336.039431ms","start":"2026-06-03T07:32:28.617354Z","end":"2026-06-03T07:32:28.953393Z","steps":["trace[1825880507] 'agreement among raft nodes before linearized reading' (duration: 335.888246ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.953436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:28.617321Z","time spent":"336.109624ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-03T07:32:28.953603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.86317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-06-03T07:32:28.953621Z","caller":"traceutil/trace.go:171","msg":"trace[1434095883] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4783; }","duration":"429.883551ms","start":"2026-06-03T07:32:28.523732Z","end":"2026-06-03T07:32:28.953615Z","steps":["trace[1434095883] 'agreement among raft nodes before linearized reading' (duration: 429.824819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.953638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:28.523687Z","time spent":"429.947213ms","remote":"127.0.0.1:51494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9659,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-06-03T07:32:28.95364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.436951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:28.953691Z","caller":"traceutil/trace.go:171","msg":"trace[149820959] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4783; }","duration":"199.539545ms","start":"2026-06-03T07:32:28.754138Z","end":"2026-06-03T07:32:28.953678Z","steps":["trace[149820959] 'agreement among raft nodes before linearized reading' (duration: 199.42782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:28.95373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.083923ms","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-06-03T07:32:28.953775Z","caller":"traceutil/trace.go:171","msg":"trace[1690036319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4783; }","duration":"162.186486ms","start":"2026-06-03T07:32:28.791576Z","end":"2026-06-03T07:32:28.953763Z","steps":["trace[1690036319] 'agreement among raft nodes before linearized reading' (duration: 162.003049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:29.25009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.777844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:32:29.250276Z","caller":"traceutil/trace.go:171","msg":"trace[495665906] linearizableReadLoop","detail":"{readStateIndex:4969; appliedIndex:4967; }","duration":"212.430834ms","start":"2026-06-03T07:32:29.037829Z","end":"2026-06-03T07:32:29.25026Z","steps":["trace[495665906] 'read index received' (duration: 97.452172ms)","trace[495665906] 'applied index is now lower than readState.Index' (duration: 114.977872ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:29.250343Z","caller":"traceutil/trace.go:171","msg":"trace[596324293] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"292.89611ms","start":"2026-06-03T07:32:28.957439Z","end":"2026-06-03T07:32:29.250336Z","steps":["trace[596324293] 'process raft request' (duration: 177.801963ms)","trace[596324293] 'compare' (duration: 114.6537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:29.250674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.822389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-06-03T07:32:29.250712Z","caller":"traceutil/trace.go:171","msg":"trace[2126721383] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"292.264276ms","start":"2026-06-03T07:32:28.95844Z","end":"2026-06-03T07:32:29.250704Z","steps":["trace[2126721383] 'process raft request' (duration: 291.757336ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:29.250741Z","caller":"traceutil/trace.go:171","msg":"trace[536320857] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4786; }","duration":"212.917972ms","start":"2026-06-03T07:32:29.037808Z","end":"2026-06-03T07:32:29.250726Z","steps":["trace[536320857] 'agreement among raft nodes before linearized reading' (duration: 212.711624ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:29.307547Z","caller":"traceutil/trace.go:171","msg":"trace[1413750956] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"133.44189ms","start":"2026-06-03T07:32:29.174078Z","end":"2026-06-03T07:32:29.30752Z","steps":["trace[1413750956] 'process raft request' (duration: 133.285134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:29.308017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.904694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-411ef038-bae5-46b6-a3a8-c52f6c05a308\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-03T07:32:29.308067Z","caller":"traceutil/trace.go:171","msg":"trace[1488605963] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-411ef038-bae5-46b6-a3a8-c52f6c05a308; range_end:; response_count:1; response_revision:4787; }","duration":"125.019678ms","start":"2026-06-03T07:32:29.183031Z","end":"2026-06-03T07:32:29.308051Z","steps":["trace[1488605963] 'agreement among raft nodes before linearized reading' (duration: 124.80594ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:29.454238Z","caller":"traceutil/trace.go:171","msg":"trace[159177346] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"141.577791ms","start":"2026-06-03T07:32:29.312637Z","end":"2026-06-03T07:32:29.454215Z","steps":["trace[159177346] 'process raft request' (duration: 135.528272ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:34.59492Z","caller":"traceutil/trace.go:171","msg":"trace[443430358] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"177.195055ms","start":"2026-06-03T07:32:34.417689Z","end":"2026-06-03T07:32:34.594884Z","steps":["trace[443430358] 'process raft request' (duration: 176.84185ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:34.595022Z","caller":"traceutil/trace.go:171","msg":"trace[1905515831] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"176.09245ms","start":"2026-06-03T07:32:34.418909Z","end":"2026-06-03T07:32:34.595002Z","steps":["trace[1905515831] 'process raft request' (duration: 175.892102ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:34.59489Z","caller":"traceutil/trace.go:171","msg":"trace[1781983041] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"176.767858ms","start":"2026-06-03T07:32:34.418073Z","end":"2026-06-03T07:32:34.594841Z","steps":["trace[1781983041] 'process raft request' (duration: 176.678554ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:35.15251Z","caller":"traceutil/trace.go:171","msg":"trace[2128036162] linearizableReadLoop","detail":"{readStateIndex:5033; appliedIndex:5032; }","duration":"104.025394ms","start":"2026-06-03T07:32:35.048462Z","end":"2026-06-03T07:32:35.152487Z","steps":["trace[2128036162] 'read index received' (duration: 103.744313ms)","trace[2128036162] 'applied index is now lower than readState.Index' (duration: 280.121µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:35.152565Z","caller":"traceutil/trace.go:171","msg":"trace[368657512] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"178.359346ms","start":"2026-06-03T07:32:34.974179Z","end":"2026-06-03T07:32:35.152538Z","steps":["trace[368657512] 'process raft request' (duration: 178.152008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:35.15281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.843466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:32:35.15283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.354707ms","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-06-03T07:32:35.152897Z","caller":"traceutil/trace.go:171","msg":"trace[359940291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4848; }","duration":"104.468712ms","start":"2026-06-03T07:32:35.048415Z","end":"2026-06-03T07:32:35.152884Z","steps":["trace[359940291] 'agreement among raft nodes before linearized reading' (duration: 104.208562ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:35.152949Z","caller":"traceutil/trace.go:171","msg":"trace[1149655348] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4848; }","duration":"101.971411ms","start":"2026-06-03T07:32:35.050897Z","end":"2026-06-03T07:32:35.152869Z","steps":["trace[1149655348] 'agreement among raft nodes before linearized reading' (duration: 101.809835ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:35.271077Z","caller":"traceutil/trace.go:171","msg":"trace[1471113584] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"112.262764ms","start":"2026-06-03T07:32:35.158788Z","end":"2026-06-03T07:32:35.271051Z","steps":["trace[1471113584] 'process raft request' (duration: 84.358507ms)","trace[1471113584] 'compare' (duration: 27.799123ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:35.275311Z","caller":"traceutil/trace.go:171","msg":"trace[21058061] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"108.866667ms","start":"2026-06-03T07:32:35.166421Z","end":"2026-06-03T07:32:35.275288Z","steps":["trace[21058061] 'process raft request' (duration: 108.775474ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:36.189808Z","caller":"traceutil/trace.go:171","msg":"trace[2009553691] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"161.858334ms","start":"2026-06-03T07:32:36.027921Z","end":"2026-06-03T07:32:36.18978Z","steps":["trace[2009553691] 'process raft request' (duration: 161.663346ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:38.77515Z","caller":"traceutil/trace.go:171","msg":"trace[477205541] linearizableReadLoop","detail":"{readStateIndex:5101; appliedIndex:5100; }","duration":"159.72212ms","start":"2026-06-03T07:32:38.615401Z","end":"2026-06-03T07:32:38.775123Z","steps":["trace[477205541] 'read index received' (duration: 150.865312ms)","trace[477205541] 'applied index is now lower than readState.Index' (duration: 8.855588ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:38.775535Z","caller":"traceutil/trace.go:171","msg":"trace[321466397] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"336.391393ms","start":"2026-06-03T07:32:38.439112Z","end":"2026-06-03T07:32:38.775504Z","steps":["trace[321466397] 'process raft request' (duration: 327.026245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:38.775697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:38.439086Z","time spent":"336.527789ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-03T07:32:38.775761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.359195ms","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-06-03T07:32:38.775816Z","caller":"traceutil/trace.go:171","msg":"trace[1492891346] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4913; }","duration":"160.465049ms","start":"2026-06-03T07:32:38.615338Z","end":"2026-06-03T07:32:38.775803Z","steps":["trace[1492891346] 'agreement among raft nodes before linearized reading' (duration: 160.280802ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:38.79928Z","caller":"traceutil/trace.go:171","msg":"trace[1034341881] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"176.148102ms","start":"2026-06-03T07:32:38.623104Z","end":"2026-06-03T07:32:38.799252Z","steps":["trace[1034341881] 'process raft request' (duration: 175.895991ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:38.799359Z","caller":"traceutil/trace.go:171","msg":"trace[2065084360] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"174.197164ms","start":"2026-06-03T07:32:38.625137Z","end":"2026-06-03T07:32:38.799335Z","steps":["trace[2065084360] 'process raft request' (duration: 173.989276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:38.799663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.877423ms","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-06-03T07:32:38.799738Z","caller":"traceutil/trace.go:171","msg":"trace[1639328077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4915; }","duration":"183.979057ms","start":"2026-06-03T07:32:38.615741Z","end":"2026-06-03T07:32:38.79972Z","steps":["trace[1639328077] 'agreement among raft nodes before linearized reading' (duration: 183.436026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:38.799663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.308101ms","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-06-03T07:32:38.799852Z","caller":"traceutil/trace.go:171","msg":"trace[955039289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4915; }","duration":"183.54424ms","start":"2026-06-03T07:32:38.616282Z","end":"2026-06-03T07:32:38.799827Z","steps":["trace[955039289] 'agreement among raft nodes before linearized reading' (duration: 183.176505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:43.544215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.863599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-06-03T07:32:43.544251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.575848ms","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-06-03T07:32:43.544277Z","caller":"traceutil/trace.go:171","msg":"trace[508494063] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4952; }","duration":"471.970503ms","start":"2026-06-03T07:32:43.072291Z","end":"2026-06-03T07:32:43.544261Z","steps":["trace[508494063] 'range keys from in-memory index tree' (duration: 471.675921ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:43.544307Z","caller":"traceutil/trace.go:171","msg":"trace[927992925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4952; }","duration":"170.678332ms","start":"2026-06-03T07:32:43.373614Z","end":"2026-06-03T07:32:43.544293Z","steps":["trace[927992925] 'range keys from in-memory index tree' (duration: 170.404911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:43.544452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.922384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-03T07:32:43.544528Z","caller":"traceutil/trace.go:171","msg":"trace[1327853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4952; }","duration":"236.024788ms","start":"2026-06-03T07:32:43.308486Z","end":"2026-06-03T07:32:43.544511Z","steps":["trace[1327853] 'range keys from in-memory index tree' (duration: 235.660583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:43.544311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:43.07227Z","time spent":"472.034135ms","remote":"127.0.0.1:51182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-03T07:32:44.350454Z","caller":"traceutil/trace.go:171","msg":"trace[652347590] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"802.026197ms","start":"2026-06-03T07:32:43.548402Z","end":"2026-06-03T07:32:44.350429Z","steps":["trace[652347590] 'process raft request' (duration: 801.888221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:44.350593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:43.548388Z","time spent":"802.134822ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:44.387057Z","caller":"traceutil/trace.go:171","msg":"trace[991067617] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5141; }","duration":"330.391818ms","start":"2026-06-03T07:32:44.056642Z","end":"2026-06-03T07:32:44.387033Z","steps":["trace[991067617] 'read index received' (duration: 293.725979ms)","trace[991067617] 'applied index is now lower than readState.Index' (duration: 36.665029ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:44.387136Z","caller":"traceutil/trace.go:171","msg":"trace[1640458486] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"837.594652ms","start":"2026-06-03T07:32:43.549511Z","end":"2026-06-03T07:32:44.387106Z","steps":["trace[1640458486] 'process raft request' (duration: 837.400384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:44.387289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.636548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-03T07:32:44.387335Z","caller":"traceutil/trace.go:171","msg":"trace[1008098598] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4954; }","duration":"330.732331ms","start":"2026-06-03T07:32:44.056588Z","end":"2026-06-03T07:32:44.38732Z","steps":["trace[1008098598] 'agreement among raft nodes before linearized reading' (duration: 330.579315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:44.387347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.729173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-03T07:32:44.38737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:44.056564Z","time spent":"330.797054ms","remote":"127.0.0.1:51182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-03T07:32:44.387348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:32:43.549485Z","time spent":"837.759778ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:32:44.387407Z","caller":"traceutil/trace.go:171","msg":"trace[880311994] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:4954; }","duration":"139.832947ms","start":"2026-06-03T07:32:44.24756Z","end":"2026-06-03T07:32:44.387393Z","steps":["trace[880311994] 'agreement among raft nodes before linearized reading' (duration: 139.719563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:44.387575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.613488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-06-03T07:32:44.387663Z","caller":"traceutil/trace.go:171","msg":"trace[1961824900] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4954; }","duration":"127.747514ms","start":"2026-06-03T07:32:44.259898Z","end":"2026-06-03T07:32:44.387645Z","steps":["trace[1961824900] 'agreement among raft nodes before linearized reading' (duration: 127.529735ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:44.387959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.428637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:32:44.388025Z","caller":"traceutil/trace.go:171","msg":"trace[903391467] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:4954; }","duration":"112.517721ms","start":"2026-06-03T07:32:44.275497Z","end":"2026-06-03T07:32:44.388014Z","steps":["trace[903391467] 'agreement among raft nodes before linearized reading' (duration: 111.884055ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:44.528711Z","caller":"traceutil/trace.go:171","msg":"trace[771775081] linearizableReadLoop","detail":"{readStateIndex:5145; appliedIndex:5144; }","duration":"105.89493ms","start":"2026-06-03T07:32:44.42278Z","end":"2026-06-03T07:32:44.528675Z","steps":["trace[771775081] 'read index received' (duration: 40.531227ms)","trace[771775081] 'applied index is now lower than readState.Index' (duration: 65.360302ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:44.528744Z","caller":"traceutil/trace.go:171","msg":"trace[954184450] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"108.296817ms","start":"2026-06-03T07:32:44.420418Z","end":"2026-06-03T07:32:44.528715Z","steps":["trace[954184450] 'process raft request' (duration: 42.877822ms)","trace[954184450] 'compare' (duration: 65.05332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:44.528934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.129909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-03T07:32:44.52898Z","caller":"traceutil/trace.go:171","msg":"trace[1971475473] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4957; }","duration":"106.206472ms","start":"2026-06-03T07:32:44.42276Z","end":"2026-06-03T07:32:44.528967Z","steps":["trace[1971475473] 'agreement among raft nodes before linearized reading' (duration: 106.018545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:45.007829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.216382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:32:45.008097Z","caller":"traceutil/trace.go:171","msg":"trace[1852143939] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"205.45029ms","start":"2026-06-03T07:32:44.802613Z","end":"2026-06-03T07:32:45.008064Z","steps":["trace[1852143939] 'read index received' (duration: 35.641µs)","trace[1852143939] 'applied index is now lower than readState.Index' (duration: 205.411029ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:45.008198Z","caller":"traceutil/trace.go:171","msg":"trace[1519257169] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"149.42485ms","start":"2026-06-03T07:32:44.858758Z","end":"2026-06-03T07:32:45.008183Z","steps":["trace[1519257169] 'process raft request' (duration: 149.164369ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:45.008255Z","caller":"traceutil/trace.go:171","msg":"trace[1733623123] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"241.014814ms","start":"2026-06-03T07:32:44.767208Z","end":"2026-06-03T07:32:45.008223Z","steps":["trace[1733623123] 'process raft request' (duration: 22.324923ms)","trace[1733623123] 'compare' (duration: 218.102567ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:45.008412Z","caller":"traceutil/trace.go:171","msg":"trace[297624727] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"148.45027ms","start":"2026-06-03T07:32:44.859941Z","end":"2026-06-03T07:32:45.008391Z","steps":["trace[297624727] 'process raft request' (duration: 148.024523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:45.008536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.478644ms","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-06-03T07:32:45.008602Z","caller":"traceutil/trace.go:171","msg":"trace[2082925292] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4965; }","duration":"140.600529ms","start":"2026-06-03T07:32:44.867984Z","end":"2026-06-03T07:32:45.008584Z","steps":["trace[2082925292] 'agreement among raft nodes before linearized reading' (duration: 140.333388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:45.008634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.987933ms","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-06-03T07:32:45.008714Z","caller":"traceutil/trace.go:171","msg":"trace[1331264682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4965; }","duration":"206.086157ms","start":"2026-06-03T07:32:44.802608Z","end":"2026-06-03T07:32:45.008694Z","steps":["trace[1331264682] 'agreement among raft nodes before linearized reading' (duration: 205.633358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:45.008819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.39935ms","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-06-03T07:32:45.008887Z","caller":"traceutil/trace.go:171","msg":"trace[1633044904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4965; }","duration":"139.488444ms","start":"2026-06-03T07:32:44.869382Z","end":"2026-06-03T07:32:45.00887Z","steps":["trace[1633044904] 'agreement among raft nodes before linearized reading' (duration: 139.322077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:45.009086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.522375ms","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-06-03T07:32:45.009206Z","caller":"traceutil/trace.go:171","msg":"trace[1796595124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4965; }","duration":"139.684412ms","start":"2026-06-03T07:32:44.869504Z","end":"2026-06-03T07:32:45.009188Z","steps":["trace[1796595124] 'agreement among raft nodes before linearized reading' (duration: 138.89928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:45.28294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.87546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-03T07:32:45.283172Z","caller":"traceutil/trace.go:171","msg":"trace[356287735] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"269.316515ms","start":"2026-06-03T07:32:45.013823Z","end":"2026-06-03T07:32:45.28314Z","steps":["trace[356287735] 'process raft request' (duration: 162.162625ms)","trace[356287735] 'compare' (duration: 106.775305ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:45.283213Z","caller":"traceutil/trace.go:171","msg":"trace[795865962] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"267.199519ms","start":"2026-06-03T07:32:45.015995Z","end":"2026-06-03T07:32:45.283194Z","steps":["trace[795865962] 'process raft request' (duration: 267.133066ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:45.283359Z","caller":"traceutil/trace.go:171","msg":"trace[616268658] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"268.527473ms","start":"2026-06-03T07:32:45.014817Z","end":"2026-06-03T07:32:45.283345Z","steps":["trace[616268658] 'process raft request' (duration: 268.268612ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:45.283346Z","caller":"traceutil/trace.go:171","msg":"trace[1937001302] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"268.597436ms","start":"2026-06-03T07:32:45.014709Z","end":"2026-06-03T07:32:45.283307Z","steps":["trace[1937001302] 'process raft request' (duration: 268.320315ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:47.184195Z","caller":"traceutil/trace.go:171","msg":"trace[63512024] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"120.151295ms","start":"2026-06-03T07:32:47.064017Z","end":"2026-06-03T07:32:47.184168Z","steps":["trace[63512024] 'read index received' (duration: 117.578009ms)","trace[63512024] 'applied index is now lower than readState.Index' (duration: 2.571346ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:47.184511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.470228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13291"} {"level":"info","ts":"2026-06-03T07:32:47.184589Z","caller":"traceutil/trace.go:171","msg":"trace[1364694020] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4989; }","duration":"120.592824ms","start":"2026-06-03T07:32:47.063979Z","end":"2026-06-03T07:32:47.184572Z","steps":["trace[1364694020] 'agreement among raft nodes before linearized reading' (duration: 120.320392ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:47.184199Z","caller":"traceutil/trace.go:171","msg":"trace[2044247498] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"127.888264ms","start":"2026-06-03T07:32:47.056294Z","end":"2026-06-03T07:32:47.184182Z","steps":["trace[2044247498] 'process raft request' (duration: 125.277606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:47.422917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.886022ms","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-06-03T07:32:47.422997Z","caller":"traceutil/trace.go:171","msg":"trace[851817138] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4992; }","duration":"135.003007ms","start":"2026-06-03T07:32:47.287975Z","end":"2026-06-03T07:32:47.422978Z","steps":["trace[851817138] 'range keys from in-memory index tree' (duration: 134.732355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:47.423282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.386622ms","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-06-03T07:32:47.423424Z","caller":"traceutil/trace.go:171","msg":"trace[182052294] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4992; }","duration":"135.58295ms","start":"2026-06-03T07:32:47.287768Z","end":"2026-06-03T07:32:47.423351Z","steps":["trace[182052294] 'range keys from in-memory index tree' (duration: 135.045749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:47.42362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.278589ms","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-06-03T07:32:47.423733Z","caller":"traceutil/trace.go:171","msg":"trace[1847927277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4992; }","duration":"136.369943ms","start":"2026-06-03T07:32:47.287291Z","end":"2026-06-03T07:32:47.423661Z","steps":["trace[1847927277] 'range keys from in-memory index tree' (duration: 136.06291ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:48.250689Z","caller":"traceutil/trace.go:171","msg":"trace[362535637] linearizableReadLoop","detail":"{readStateIndex:5187; appliedIndex:5186; }","duration":"146.823906ms","start":"2026-06-03T07:32:48.10384Z","end":"2026-06-03T07:32:48.250664Z","steps":["trace[362535637] 'read index received' (duration: 133.205564ms)","trace[362535637] 'applied index is now lower than readState.Index' (duration: 13.617262ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:32:48.250774Z","caller":"traceutil/trace.go:171","msg":"trace[2075558894] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"166.402474ms","start":"2026-06-03T07:32:48.084331Z","end":"2026-06-03T07:32:48.250734Z","steps":["trace[2075558894] 'process raft request' (duration: 152.800743ms)","trace[2075558894] 'compare' (duration: 13.411973ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:32:48.25095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.093918ms","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-06-03T07:32:48.251004Z","caller":"traceutil/trace.go:171","msg":"trace[1217153429] 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:4997; }","duration":"147.188021ms","start":"2026-06-03T07:32:48.103799Z","end":"2026-06-03T07:32:48.250987Z","steps":["trace[1217153429] 'agreement among raft nodes before linearized reading' (duration: 146.969982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.506339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.480489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-03T07:32:48.506544Z","caller":"traceutil/trace.go:171","msg":"trace[1510380855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4998; }","duration":"143.661996ms","start":"2026-06-03T07:32:48.362796Z","end":"2026-06-03T07:32:48.506458Z","steps":["trace[1510380855] 'range keys from in-memory index tree' (duration: 143.02445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:32:48.506576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.687089ms","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-06-03T07:32:48.506634Z","caller":"traceutil/trace.go:171","msg":"trace[872150289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4999; }","duration":"105.783313ms","start":"2026-06-03T07:32:48.400837Z","end":"2026-06-03T07:32:48.50662Z","steps":["trace[872150289] 'agreement among raft nodes before linearized reading' (duration: 105.625097ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:48.506764Z","caller":"traceutil/trace.go:171","msg":"trace[936944220] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"107.247383ms","start":"2026-06-03T07:32:48.39951Z","end":"2026-06-03T07:32:48.506757Z","steps":["trace[936944220] 'process raft request' (duration: 106.594306ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:32:48.506947Z","caller":"traceutil/trace.go:171","msg":"trace[423182687] linearizableReadLoop","detail":"{readStateIndex:5189; appliedIndex:5188; }","duration":"105.578065ms","start":"2026-06-03T07:32:48.40088Z","end":"2026-06-03T07:32:48.506458Z","steps":["trace[423182687] 'read index received' (duration: 105.170068ms)","trace[423182687] 'applied index is now lower than readState.Index' (duration: 407.317µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:22.923773Z","caller":"traceutil/trace.go:171","msg":"trace[1593123509] linearizableReadLoop","detail":"{readStateIndex:5498; appliedIndex:5497; }","duration":"155.332671ms","start":"2026-06-03T07:33:22.768416Z","end":"2026-06-03T07:33:22.923749Z","steps":["trace[1593123509] 'read index received' (duration: 155.09789ms)","trace[1593123509] 'applied index is now lower than readState.Index' (duration: 233.791µs)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:22.923834Z","caller":"traceutil/trace.go:171","msg":"trace[2060083901] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"174.070074ms","start":"2026-06-03T07:33:22.749742Z","end":"2026-06-03T07:33:22.923812Z","steps":["trace[2060083901] 'process raft request' (duration: 173.856085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:22.92404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.605793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-03T07:33:22.924119Z","caller":"traceutil/trace.go:171","msg":"trace[328612122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5300; }","duration":"155.740079ms","start":"2026-06-03T07:33:22.768364Z","end":"2026-06-03T07:33:22.924104Z","steps":["trace[328612122] 'agreement among raft nodes before linearized reading' (duration: 155.52796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:22.924114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.226468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:33:22.92429Z","caller":"traceutil/trace.go:171","msg":"trace[2055507791] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5300; }","duration":"122.423836ms","start":"2026-06-03T07:33:22.801817Z","end":"2026-06-03T07:33:22.924241Z","steps":["trace[2055507791] 'agreement among raft nodes before linearized reading' (duration: 122.187406ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:27.314653Z","caller":"traceutil/trace.go:171","msg":"trace[640168223] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"125.549655ms","start":"2026-06-03T07:33:27.189072Z","end":"2026-06-03T07:33:27.314622Z","steps":["trace[640168223] 'process raft request' (duration: 125.033602ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:31.551789Z","caller":"traceutil/trace.go:171","msg":"trace[1931386662] linearizableReadLoop","detail":"{readStateIndex:5552; appliedIndex:5551; }","duration":"219.035868ms","start":"2026-06-03T07:33:31.332726Z","end":"2026-06-03T07:33:31.551761Z","steps":["trace[1931386662] 'read index received' (duration: 188.602564ms)","trace[1931386662] 'applied index is now lower than readState.Index' (duration: 30.431874ms)"],"step_count":2} {"level":"info","ts":"2026-06-03T07:33:31.551837Z","caller":"traceutil/trace.go:171","msg":"trace[252884274] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"220.487023ms","start":"2026-06-03T07:33:31.33133Z","end":"2026-06-03T07:33:31.551817Z","steps":["trace[252884274] 'process raft request' (duration: 189.890801ms)","trace[252884274] 'compare' (duration: 30.34026ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:33:31.552261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.511989ms","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-06-03T07:33:31.552336Z","caller":"traceutil/trace.go:171","msg":"trace[321504578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5351; }","duration":"219.616814ms","start":"2026-06-03T07:33:31.332702Z","end":"2026-06-03T07:33:31.552319Z","steps":["trace[321504578] 'agreement among raft nodes before linearized reading' (duration: 219.150803ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:31.581239Z","caller":"traceutil/trace.go:171","msg":"trace[1211141409] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"198.60808ms","start":"2026-06-03T07:33:31.382574Z","end":"2026-06-03T07:33:31.581182Z","steps":["trace[1211141409] 'process raft request' (duration: 198.253864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:31.581664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.07672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-06-03T07:33:31.581729Z","caller":"traceutil/trace.go:171","msg":"trace[847269068] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5352; }","duration":"199.185215ms","start":"2026-06-03T07:33:31.382529Z","end":"2026-06-03T07:33:31.581715Z","steps":["trace[847269068] 'agreement among raft nodes before linearized reading' (duration: 198.441252ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:40.398006Z","caller":"traceutil/trace.go:171","msg":"trace[323918301] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"123.586149ms","start":"2026-06-03T07:33:40.27439Z","end":"2026-06-03T07:33:40.397976Z","steps":["trace[323918301] 'process raft request' (duration: 104.398665ms)","trace[323918301] 'compare' (duration: 18.982235ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:33:41.577096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.335008ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-03T07:33:41.577155Z","caller":"traceutil/trace.go:171","msg":"trace[1729560999] linearizableReadLoop","detail":"{readStateIndex:5628; appliedIndex:5627; }","duration":"239.070546ms","start":"2026-06-03T07:33:41.338075Z","end":"2026-06-03T07:33:41.577145Z","steps":["trace[1729560999] 'read index received' (duration: 50.192µs)","trace[1729560999] 'applied index is now lower than readState.Index' (duration: 239.019204ms)"],"step_count":2} {"level":"warn","ts":"2026-06-03T07:33:41.57756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.479244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-03T07:33:41.577591Z","caller":"traceutil/trace.go:171","msg":"trace[1870165855] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5425; }","duration":"239.542548ms","start":"2026-06-03T07:33:41.338041Z","end":"2026-06-03T07:33:41.577584Z","steps":["trace[1870165855] 'agreement among raft nodes before linearized reading' (duration: 239.472134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:33:41.975666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.195432ms","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-06-03T07:33:41.975769Z","caller":"traceutil/trace.go:171","msg":"trace[25966188] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5426; }","duration":"157.388901ms","start":"2026-06-03T07:33:41.818352Z","end":"2026-06-03T07:33:41.975741Z","steps":["trace[25966188] 'range keys from in-memory index tree' (duration: 157.055496ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:33:43.234096Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2825} {"level":"info","ts":"2026-06-03T07:33:43.305286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2825,"took":"69.386179ms","hash":2055844958,"current-db-size-bytes":53268480,"current-db-size":"53 MB","current-db-size-in-use-bytes":28631040,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-03T07:33:43.305334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2055844958,"revision":2825,"compact-revision":-1} {"level":"info","ts":"2026-06-03T07:33:46.381985Z","caller":"traceutil/trace.go:171","msg":"trace[1079281421] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"116.229448ms","start":"2026-06-03T07:33:46.265729Z","end":"2026-06-03T07:33:46.381958Z","steps":["trace[1079281421] 'process raft request' (duration: 116.066971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:35:43.515874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.112528ms","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-06-03T07:35:43.515945Z","caller":"traceutil/trace.go:171","msg":"trace[2043848670] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6180; }","duration":"220.231104ms","start":"2026-06-03T07:35:43.2957Z","end":"2026-06-03T07:35:43.515931Z","steps":["trace[2043848670] 'count revisions from in-memory index tree' (duration: 220.041755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:35:43.515873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.81301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-03T07:35:43.516731Z","caller":"traceutil/trace.go:171","msg":"trace[381310378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6180; }","duration":"578.710655ms","start":"2026-06-03T07:35:42.937994Z","end":"2026-06-03T07:35:43.516705Z","steps":["trace[381310378] 'range keys from in-memory index tree' (duration: 577.729405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:35:43.516814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:35:42.937973Z","time spent":"578.801579ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-03T07:35:43.984036Z","caller":"traceutil/trace.go:171","msg":"trace[492834950] transaction","detail":"{read_only:false; response_revision:6181; number_of_response:1; }","duration":"324.285001ms","start":"2026-06-03T07:35:43.65972Z","end":"2026-06-03T07:35:43.984005Z","steps":["trace[492834950] 'process raft request' (duration: 324.087741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-03T07:35:43.984237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-03T07:35:43.659697Z","time spent":"324.439048ms","remote":"127.0.0.1:51294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-03T07:35:43.985471Z","caller":"traceutil/trace.go:171","msg":"trace[2008053449] transaction","detail":"{read_only:false; response_revision:6182; number_of_response:1; }","duration":"294.622344ms","start":"2026-06-03T07:35:43.69083Z","end":"2026-06-03T07:35:43.985452Z","steps":["trace[2008053449] 'process raft request' (duration: 294.364102ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:35:43.985461Z","caller":"traceutil/trace.go:171","msg":"trace[183041665] transaction","detail":"{read_only:false; response_revision:6183; number_of_response:1; }","duration":"192.396179ms","start":"2026-06-03T07:35:43.793031Z","end":"2026-06-03T07:35:43.985428Z","steps":["trace[183041665] 'process raft request' (duration: 192.267083ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:38:40.268918Z","caller":"traceutil/trace.go:171","msg":"trace[1915369174] transaction","detail":"{read_only:false; response_revision:7330; number_of_response:1; }","duration":"112.994869ms","start":"2026-06-03T07:38:40.155903Z","end":"2026-06-03T07:38:40.268897Z","steps":["trace[1915369174] 'process raft request' (duration: 112.871728ms)"],"step_count":1} {"level":"info","ts":"2026-06-03T07:38:43.241554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5428} {"level":"info","ts":"2026-06-03T07:38:43.380082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5428,"took":"136.925381ms","hash":956744325,"current-db-size-bytes":53268480,"current-db-size":"53 MB","current-db-size-in-use-bytes":18391040,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-03T07:38:43.380158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":956744325,"revision":5428,"compact-revision":2825}