{"level":"warn","ts":"2026-04-16T11:41:22.288927Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T11:41:22.289007Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-04-16T11:41:22.289099Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T11:41:22.289113Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-16T11:41:22.28913Z","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-04-16T11:41:22.289738Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-16T11:41:22.289811Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-04-16T11:41:22.294873Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.865711ms"} {"level":"info","ts":"2026-04-16T11:41:22.305002Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-16T11:41:22.305069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T11:41:22.305127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-16T11:41:22.305149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T11:41:22.305164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-16T11:41:22.305204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-16T11:41:22.311284Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T11:41:22.313038Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T11:41:22.31446Z","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-04-16T11:41:22.317943Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T11:41:22.3192Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T11:41:22.319305Z","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-04-16T11:41:22.320755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-16T11:41:22.321281Z","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-04-16T11:41:22.321365Z","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-04-16T11:41:22.32185Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-16T11:41:22.32563Z","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-04-16T11:41:22.325838Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-16T11:41:22.325909Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-16T11:41:22.32607Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T11:41:22.326153Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T11:41:22.80613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T11:41:22.80618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T11:41:22.806209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-16T11:41:22.806222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-16T11:41:22.806257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-16T11:41:22.806265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-16T11:41:22.806277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-16T11:41:22.816385Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:41:22.826361Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T11:41:22.826411Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T11:41:22.826432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T11:41:22.826745Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T11:41:22.826832Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T11:41:22.829126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T11:41:22.829332Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:41:22.829508Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:41:22.82957Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T11:41:22.831024Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-16T11:41:28.320075Z","caller":"traceutil/trace.go:171","msg":"trace[1486976226] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"306.058186ms","start":"2026-04-16T11:41:28.013993Z","end":"2026-04-16T11:41:28.320052Z","steps":["trace[1486976226] 'process raft request' (duration: 305.868737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:41:28.320551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:41:28.013964Z","time spent":"306.199783ms","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T11:41:28.739773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.262089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:41:28.739911Z","caller":"traceutil/trace.go:171","msg":"trace[732744432] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"417.567838ms","start":"2026-04-16T11:41:28.322326Z","end":"2026-04-16T11:41:28.739894Z","steps":["trace[732744432] 'read index received' (duration: 66.818365ms)","trace[732744432] 'applied index is now lower than readState.Index' (duration: 350.747823ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:41:28.740126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.738796ms","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-04-16T11:41:28.740135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.553259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-16T11:41:28.740182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.733891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:41:28.740222Z","caller":"traceutil/trace.go:171","msg":"trace[1093462979] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:300; }","duration":"416.656398ms","start":"2026-04-16T11:41:28.323539Z","end":"2026-04-16T11:41:28.740195Z","steps":["trace[1093462979] 'agreement among raft nodes before linearized reading' (duration: 416.460101ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:28.740202Z","caller":"traceutil/trace.go:171","msg":"trace[852069240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:300; }","duration":"417.914734ms","start":"2026-04-16T11:41:28.322267Z","end":"2026-04-16T11:41:28.740182Z","steps":["trace[852069240] 'agreement among raft nodes before linearized reading' (duration: 417.702257ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:28.74024Z","caller":"traceutil/trace.go:171","msg":"trace[559154958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:300; }","duration":"296.85359ms","start":"2026-04-16T11:41:28.443371Z","end":"2026-04-16T11:41:28.740216Z","steps":["trace[559154958] 'agreement among raft nodes before linearized reading' (duration: 296.753761ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:28.740212Z","caller":"traceutil/trace.go:171","msg":"trace[1934459243] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"655.77042ms","start":"2026-04-16T11:41:28.084366Z","end":"2026-04-16T11:41:28.740136Z","steps":["trace[1934459243] 'process raft request' (duration: 304.893818ms)","trace[1934459243] 'compare' (duration: 349.651346ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:41:28.740277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:41:28.322246Z","time spent":"418.022363ms","remote":"127.0.0.1:54870","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-04-16T11:41:28.740333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.530196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T11:41:28.74036Z","caller":"traceutil/trace.go:171","msg":"trace[1161645327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:300; }","duration":"384.577345ms","start":"2026-04-16T11:41:28.355774Z","end":"2026-04-16T11:41:28.740352Z","steps":["trace[1161645327] 'agreement among raft nodes before linearized reading' (duration: 384.526476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:41:28.740275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:41:28.32351Z","time spent":"416.756107ms","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-04-16T11:41:28.740385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:41:28.355763Z","time spent":"384.616846ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T11:41:28.740382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:41:28.084344Z","time spent":"655.960418ms","remote":"127.0.0.1:54818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T11:41:28.892143Z","caller":"traceutil/trace.go:171","msg":"trace[1120425880] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"147.578279ms","start":"2026-04-16T11:41:28.744542Z","end":"2026-04-16T11:41:28.89212Z","steps":["trace[1120425880] 'process raft request' (duration: 147.45275ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:28.895109Z","caller":"traceutil/trace.go:171","msg":"trace[1881385317] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"150.012012ms","start":"2026-04-16T11:41:28.745068Z","end":"2026-04-16T11:41:28.89508Z","steps":["trace[1881385317] 'read index received' (duration: 147.133135ms)","trace[1881385317] 'applied index is now lower than readState.Index' (duration: 2.877217ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:41:28.895276Z","caller":"traceutil/trace.go:171","msg":"trace[788903921] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"149.133732ms","start":"2026-04-16T11:41:28.746071Z","end":"2026-04-16T11:41:28.895204Z","steps":["trace[788903921] 'process raft request' (duration: 148.733787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:41:28.895336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.247868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:41:28.895379Z","caller":"traceutil/trace.go:171","msg":"trace[1611813530] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:305; }","duration":"150.354588ms","start":"2026-04-16T11:41:28.745012Z","end":"2026-04-16T11:41:28.895367Z","steps":["trace[1611813530] 'agreement among raft nodes before linearized reading' (duration: 150.18965ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:28.895627Z","caller":"traceutil/trace.go:171","msg":"trace[839664553] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"148.620827ms","start":"2026-04-16T11:41:28.746995Z","end":"2026-04-16T11:41:28.895616Z","steps":["trace[839664553] 'process raft request' (duration: 147.867106ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:28.895661Z","caller":"traceutil/trace.go:171","msg":"trace[74036585] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"150.647754ms","start":"2026-04-16T11:41:28.744972Z","end":"2026-04-16T11:41:28.89562Z","steps":["trace[74036585] 'process raft request' (duration: 149.697086ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:28.895732Z","caller":"traceutil/trace.go:171","msg":"trace[38198334] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"136.823473ms","start":"2026-04-16T11:41:28.758895Z","end":"2026-04-16T11:41:28.895718Z","steps":["trace[38198334] 'process raft request' (duration: 136.016142ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:48.896545Z","caller":"traceutil/trace.go:171","msg":"trace[1780309032] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"114.647293ms","start":"2026-04-16T11:41:48.781866Z","end":"2026-04-16T11:41:48.896513Z","steps":["trace[1780309032] 'process raft request' (duration: 114.526992ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:48.903351Z","caller":"traceutil/trace.go:171","msg":"trace[867076217] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"120.498179ms","start":"2026-04-16T11:41:48.782834Z","end":"2026-04-16T11:41:48.903332Z","steps":["trace[867076217] 'process raft request' (duration: 120.191717ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:48.903646Z","caller":"traceutil/trace.go:171","msg":"trace[2073395804] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"120.497809ms","start":"2026-04-16T11:41:48.783116Z","end":"2026-04-16T11:41:48.903613Z","steps":["trace[2073395804] 'process raft request' (duration: 120.088126ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:48.903712Z","caller":"traceutil/trace.go:171","msg":"trace[35239154] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"117.153752ms","start":"2026-04-16T11:41:48.78655Z","end":"2026-04-16T11:41:48.903703Z","steps":["trace[35239154] 'process raft request' (duration: 116.995022ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:48.903879Z","caller":"traceutil/trace.go:171","msg":"trace[126453476] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"116.065084ms","start":"2026-04-16T11:41:48.787762Z","end":"2026-04-16T11:41:48.903827Z","steps":["trace[126453476] 'process raft request' (duration: 115.851843ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:48.904295Z","caller":"traceutil/trace.go:171","msg":"trace[927352373] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"110.21414ms","start":"2026-04-16T11:41:48.794069Z","end":"2026-04-16T11:41:48.904283Z","steps":["trace[927352373] 'process raft request' (duration: 109.672176ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:49.355981Z","caller":"traceutil/trace.go:171","msg":"trace[174530762] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"172.64574ms","start":"2026-04-16T11:41:49.183302Z","end":"2026-04-16T11:41:49.355947Z","steps":["trace[174530762] 'process raft request' (duration: 164.062876ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:49.356169Z","caller":"traceutil/trace.go:171","msg":"trace[1782421218] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"157.624662ms","start":"2026-04-16T11:41:49.198517Z","end":"2026-04-16T11:41:49.356142Z","steps":["trace[1782421218] 'process raft request' (duration: 157.400441ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:49.356278Z","caller":"traceutil/trace.go:171","msg":"trace[720063160] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"107.385455ms","start":"2026-04-16T11:41:49.248864Z","end":"2026-04-16T11:41:49.356249Z","steps":["trace[720063160] 'process raft request' (duration: 107.227103ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:41:55.333615Z","caller":"traceutil/trace.go:171","msg":"trace[474477107] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"205.99444ms","start":"2026-04-16T11:41:55.127584Z","end":"2026-04-16T11:41:55.333578Z","steps":["trace[474477107] 'process raft request' (duration: 205.294901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:41:55.968476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.374334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:41:55.968625Z","caller":"traceutil/trace.go:171","msg":"trace[429702343] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"206.285544ms","start":"2026-04-16T11:41:55.762316Z","end":"2026-04-16T11:41:55.968601Z","steps":["trace[429702343] 'read index received' (duration: 3.730519ms)","trace[429702343] 'applied index is now lower than readState.Index' (duration: 202.553545ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:41:55.968837Z","caller":"traceutil/trace.go:171","msg":"trace[1001779546] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"572.738958ms","start":"2026-04-16T11:41:55.396083Z","end":"2026-04-16T11:41:55.968822Z","steps":["trace[1001779546] 'process raft request' (duration: 369.907549ms)","trace[1001779546] 'compare' (duration: 201.829576ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:41:55.968901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:41:55.396053Z","time spent":"572.817149ms","remote":"127.0.0.1:55074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T11:41:55.969454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.123665ms","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-04-16T11:41:55.969495Z","caller":"traceutil/trace.go:171","msg":"trace[1808623677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"207.202086ms","start":"2026-04-16T11:41:55.762279Z","end":"2026-04-16T11:41:55.969481Z","steps":["trace[1808623677] 'agreement among raft nodes before linearized reading' (duration: 206.993733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:41:55.969673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.398707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2brjb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-16T11:41:55.969711Z","caller":"traceutil/trace.go:171","msg":"trace[472983366] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2brjb; range_end:; response_count:1; response_revision:612; }","duration":"184.495878ms","start":"2026-04-16T11:41:55.785206Z","end":"2026-04-16T11:41:55.969702Z","steps":["trace[472983366] 'agreement among raft nodes before linearized reading' (duration: 184.422548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:42:13.300347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.763985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:42:13.300479Z","caller":"traceutil/trace.go:171","msg":"trace[68448562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"219.937ms","start":"2026-04-16T11:42:13.080523Z","end":"2026-04-16T11:42:13.30046Z","steps":["trace[68448562] 'range keys from in-memory index tree' (duration: 219.518389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:42:41.001457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.611079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:42:41.001712Z","caller":"traceutil/trace.go:171","msg":"trace[1359866351] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"109.866846ms","start":"2026-04-16T11:42:40.891803Z","end":"2026-04-16T11:42:41.00167Z","steps":["trace[1359866351] 'compare' (duration: 105.447044ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:42:41.002016Z","caller":"traceutil/trace.go:171","msg":"trace[1916823317] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1176; }","duration":"108.449983ms","start":"2026-04-16T11:42:40.893548Z","end":"2026-04-16T11:42:41.001998Z","steps":["trace[1916823317] 'read index received' (duration: 2.095917ms)","trace[1916823317] 'applied index is now lower than readState.Index' (duration: 106.352626ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:42:41.002232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.666811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager\" ","response":"range_response_count:1 size:4685"} {"level":"info","ts":"2026-04-16T11:42:41.002261Z","caller":"traceutil/trace.go:171","msg":"trace[1016446079] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager; range_end:; response_count:1; response_revision:1151; }","duration":"108.724873ms","start":"2026-04-16T11:42:40.893528Z","end":"2026-04-16T11:42:41.002253Z","steps":["trace[1016446079] 'agreement among raft nodes before linearized reading' (duration: 108.571888ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:42:41.005315Z","caller":"traceutil/trace.go:171","msg":"trace[1827092002] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"101.013061ms","start":"2026-04-16T11:42:40.904267Z","end":"2026-04-16T11:42:41.00528Z","steps":["trace[1827092002] 'process raft request' (duration: 100.591635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:42:41.01073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.39715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-75c4c745bc\" ","response":"range_response_count:1 size:4425"} {"level":"warn","ts":"2026-04-16T11:42:41.01075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.381359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64\" ","response":"range_response_count:1 size:4690"} {"level":"info","ts":"2026-04-16T11:42:41.010782Z","caller":"traceutil/trace.go:171","msg":"trace[1526319445] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-75c4c745bc; range_end:; response_count:1; response_revision:1155; }","duration":"106.458031ms","start":"2026-04-16T11:42:40.904313Z","end":"2026-04-16T11:42:41.010771Z","steps":["trace[1526319445] 'agreement among raft nodes before linearized reading' (duration: 106.337667ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:42:41.010795Z","caller":"traceutil/trace.go:171","msg":"trace[1438575525] range","detail":"{range_begin:/registry/replicasets/cert-manager/cert-manager-webhook-548949fc64; range_end:; response_count:1; response_revision:1155; }","duration":"106.442531ms","start":"2026-04-16T11:42:40.904337Z","end":"2026-04-16T11:42:41.010779Z","steps":["trace[1438575525] 'agreement among raft nodes before linearized reading' (duration: 106.277245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:42:41.010964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.731663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:4950"} {"level":"info","ts":"2026-04-16T11:42:41.011042Z","caller":"traceutil/trace.go:171","msg":"trace[1112122398] range","detail":"{range_begin:/registry/deployments/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:1155; }","duration":"106.830196ms","start":"2026-04-16T11:42:40.904194Z","end":"2026-04-16T11:42:41.011024Z","steps":["trace[1112122398] 'agreement among raft nodes before linearized reading' (duration: 106.643949ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:42:42.158452Z","caller":"traceutil/trace.go:171","msg":"trace[1215851800] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"136.941845ms","start":"2026-04-16T11:42:42.021469Z","end":"2026-04-16T11:42:42.15841Z","steps":["trace[1215851800] 'process raft request' (duration: 136.599442ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:42:59.411617Z","caller":"traceutil/trace.go:171","msg":"trace[1333490095] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"196.787011ms","start":"2026-04-16T11:42:59.214796Z","end":"2026-04-16T11:42:59.411583Z","steps":["trace[1333490095] 'process raft request' (duration: 196.567262ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:42:59.419028Z","caller":"traceutil/trace.go:171","msg":"trace[47400749] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"174.173448ms","start":"2026-04-16T11:42:59.244839Z","end":"2026-04-16T11:42:59.419012Z","steps":["trace[47400749] 'process raft request' (duration: 174.075064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.639701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.971397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c58a61a4-e055-4f2d-9e4d-a00daaccaaf8\" ","response":"range_response_count:1 size:3736"} {"level":"info","ts":"2026-04-16T11:44:19.639789Z","caller":"traceutil/trace.go:171","msg":"trace[2068365066] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c58a61a4-e055-4f2d-9e4d-a00daaccaaf8; range_end:; response_count:1; response_revision:2163; }","duration":"163.088443ms","start":"2026-04-16T11:44:19.476688Z","end":"2026-04-16T11:44:19.639776Z","steps":["trace[2068365066] 'agreement among raft nodes before linearized reading' (duration: 162.919784ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.639601Z","caller":"traceutil/trace.go:171","msg":"trace[53325303] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2240; }","duration":"162.84298ms","start":"2026-04-16T11:44:19.476703Z","end":"2026-04-16T11:44:19.639545Z","steps":["trace[53325303] 'read index received' (duration: 49.069923ms)","trace[53325303] 'applied index is now lower than readState.Index' (duration: 113.771617ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:19.640177Z","caller":"traceutil/trace.go:171","msg":"trace[1439346147] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"164.007614ms","start":"2026-04-16T11:44:19.476152Z","end":"2026-04-16T11:44:19.640159Z","steps":["trace[1439346147] 'process raft request' (duration: 163.313976ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.640203Z","caller":"traceutil/trace.go:171","msg":"trace[857155776] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"166.672769ms","start":"2026-04-16T11:44:19.473501Z","end":"2026-04-16T11:44:19.640174Z","steps":["trace[857155776] 'process raft request' (duration: 165.728037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.985397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.945787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:44:19.985572Z","caller":"traceutil/trace.go:171","msg":"trace[2084823303] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"446.718305ms","start":"2026-04-16T11:44:19.538811Z","end":"2026-04-16T11:44:19.98553Z","steps":["trace[2084823303] 'process raft request' (duration: 317.220237ms)","trace[2084823303] 'compare' (duration: 128.160335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:19.985909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.538788Z","time spent":"446.964989ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:44:19.986018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.294965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.986112Z","caller":"traceutil/trace.go:171","msg":"trace[520233453] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2164; }","duration":"361.405393ms","start":"2026-04-16T11:44:19.624659Z","end":"2026-04-16T11:44:19.986064Z","steps":["trace[520233453] 'agreement among raft nodes before linearized reading' (duration: 361.294937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.986175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.62465Z","time spent":"361.510778ms","remote":"127.0.0.1:53210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T11:44:19.98615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.802068ms","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":"warn","ts":"2026-04-16T11:44:19.985874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.857255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.986252Z","caller":"traceutil/trace.go:171","msg":"trace[1148139553] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2164; }","duration":"377.952447ms","start":"2026-04-16T11:44:19.608272Z","end":"2026-04-16T11:44:19.986225Z","steps":["trace[1148139553] 'agreement among raft nodes before linearized reading' (duration: 377.708524ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.9863Z","caller":"traceutil/trace.go:171","msg":"trace[202566167] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2164; }","duration":"398.345911ms","start":"2026-04-16T11:44:19.587937Z","end":"2026-04-16T11:44:19.986282Z","steps":["trace[202566167] 'agreement among raft nodes before linearized reading' (duration: 397.801441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.986359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.800404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.986402Z","caller":"traceutil/trace.go:171","msg":"trace[505221807] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2164; }","duration":"361.865338ms","start":"2026-04-16T11:44:19.624528Z","end":"2026-04-16T11:44:19.986393Z","steps":["trace[505221807] 'agreement among raft nodes before linearized reading' (duration: 361.805824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.986438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.624481Z","time spent":"361.949151ms","remote":"127.0.0.1:53210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-04-16T11:44:19.986487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.474921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.986535Z","caller":"traceutil/trace.go:171","msg":"trace[1532401997] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2164; }","duration":"379.545603ms","start":"2026-04-16T11:44:19.606977Z","end":"2026-04-16T11:44:19.986522Z","steps":["trace[1532401997] 'agreement among raft nodes before linearized reading' (duration: 379.478809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.986569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.606964Z","time spent":"379.596428ms","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T11:44:19.98663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.172874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.986667Z","caller":"traceutil/trace.go:171","msg":"trace[1025564127] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:2165; }","duration":"362.212357ms","start":"2026-04-16T11:44:19.624445Z","end":"2026-04-16T11:44:19.986658Z","steps":["trace[1025564127] 'agreement among raft nodes before linearized reading' (duration: 362.129262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.986699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.624413Z","time spent":"362.27792ms","remote":"127.0.0.1:53210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" limit:500 "} {"level":"warn","ts":"2026-04-16T11:44:19.986535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.271041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.986844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.582852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.986903Z","caller":"traceutil/trace.go:171","msg":"trace[2147737] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2164; }","duration":"378.713598ms","start":"2026-04-16T11:44:19.608178Z","end":"2026-04-16T11:44:19.986891Z","steps":["trace[2147737] 'agreement among raft nodes before linearized reading' (duration: 378.259154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.986363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.587924Z","time spent":"398.420896ms","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:500 "} {"level":"warn","ts":"2026-04-16T11:44:19.986945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.608145Z","time spent":"378.790472ms","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" limit:500 "} {"level":"warn","ts":"2026-04-16T11:44:19.986306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.60825Z","time spent":"378.045001ms","remote":"127.0.0.1:53206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-04-16T11:44:19.986816Z","caller":"traceutil/trace.go:171","msg":"trace[290039709] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"222.078479ms","start":"2026-04-16T11:44:19.764708Z","end":"2026-04-16T11:44:19.986787Z","steps":["trace[290039709] 'process raft request' (duration: 221.482456ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.988079Z","caller":"traceutil/trace.go:171","msg":"trace[577753689] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2164; }","duration":"417.134708ms","start":"2026-04-16T11:44:19.569693Z","end":"2026-04-16T11:44:19.986828Z","steps":["trace[577753689] 'agreement among raft nodes before linearized reading' (duration: 416.220387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.987146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.451745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.988308Z","caller":"traceutil/trace.go:171","msg":"trace[884087047] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2165; }","duration":"418.597708ms","start":"2026-04-16T11:44:19.569682Z","end":"2026-04-16T11:44:19.98828Z","steps":["trace[884087047] 'agreement among raft nodes before linearized reading' (duration: 417.431495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.988372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.569659Z","time spent":"418.694443ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T11:44:19.988231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.569691Z","time spent":"418.45607ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-04-16T11:44:19.987202Z","caller":"traceutil/trace.go:171","msg":"trace[1610044257] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"166.592414ms","start":"2026-04-16T11:44:19.820598Z","end":"2026-04-16T11:44:19.98719Z","steps":["trace[1610044257] 'process raft request' (duration: 166.043504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.98728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.313793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.988701Z","caller":"traceutil/trace.go:171","msg":"trace[80340326] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2165; }","duration":"417.747071ms","start":"2026-04-16T11:44:19.570942Z","end":"2026-04-16T11:44:19.988689Z","steps":["trace[80340326] 'agreement among raft nodes before linearized reading' (duration: 415.977845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.988745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.570938Z","time spent":"417.795295ms","remote":"127.0.0.1:53190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" limit:500 "} {"level":"warn","ts":"2026-04-16T11:44:19.987321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.351451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.988968Z","caller":"traceutil/trace.go:171","msg":"trace[1635341009] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2165; }","duration":"401.949458ms","start":"2026-04-16T11:44:19.58696Z","end":"2026-04-16T11:44:19.988909Z","steps":["trace[1635341009] 'agreement among raft nodes before linearized reading' (duration: 399.922327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.98735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.381853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.987362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.167588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.987437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.181344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.987637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.493808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.987565Z","caller":"traceutil/trace.go:171","msg":"trace[331214957] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2243; }","duration":"345.769868ms","start":"2026-04-16T11:44:19.63991Z","end":"2026-04-16T11:44:19.98568Z","steps":["trace[331214957] 'read index received' (duration: 216.207097ms)","trace[331214957] 'applied index is now lower than readState.Index' (duration: 129.557731ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:19.98914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.58693Z","time spent":"402.140249ms","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" limit:10000 "} {"level":"info","ts":"2026-04-16T11:44:19.989169Z","caller":"traceutil/trace.go:171","msg":"trace[1153653130] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2166; }","duration":"435.983859ms","start":"2026-04-16T11:44:19.553172Z","end":"2026-04-16T11:44:19.989156Z","steps":["trace[1153653130] 'agreement among raft nodes before linearized reading' (duration: 434.137968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.989273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.295202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.989313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.993611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.989189Z","caller":"traceutil/trace.go:171","msg":"trace[1618178186] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2166; }","duration":"436.058042ms","start":"2026-04-16T11:44:19.553108Z","end":"2026-04-16T11:44:19.989167Z","steps":["trace[1618178186] 'agreement among raft nodes before linearized reading' (duration: 434.495487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.989378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.553089Z","time spent":"436.276014ms","remote":"127.0.0.1:53180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-04-16T11:44:19.989354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.553165Z","time spent":"436.170309ms","remote":"127.0.0.1:53180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" limit:10000 "} {"level":"info","ts":"2026-04-16T11:44:19.989442Z","caller":"traceutil/trace.go:171","msg":"trace[1927537894] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:2165; }","duration":"435.136132ms","start":"2026-04-16T11:44:19.554205Z","end":"2026-04-16T11:44:19.989341Z","steps":["trace[1927537894] 'agreement among raft nodes before linearized reading' (duration: 432.886329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.989439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.698834ms","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":"info","ts":"2026-04-16T11:44:19.989529Z","caller":"traceutil/trace.go:171","msg":"trace[981034907] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2166; }","duration":"270.80166ms","start":"2026-04-16T11:44:19.718717Z","end":"2026-04-16T11:44:19.989519Z","steps":["trace[981034907] 'agreement among raft nodes before linearized reading' (duration: 270.688045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.989623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.554185Z","time spent":"435.361404ms","remote":"127.0.0.1:53180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" limit:500 "} {"level":"info","ts":"2026-04-16T11:44:19.989786Z","caller":"traceutil/trace.go:171","msg":"trace[1476489586] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2165; }","duration":"402.841687ms","start":"2026-04-16T11:44:19.58693Z","end":"2026-04-16T11:44:19.989772Z","steps":["trace[1476489586] 'agreement among raft nodes before linearized reading' (duration: 399.972041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.989844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.586912Z","time spent":"402.916711ms","remote":"127.0.0.1:53198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-04-16T11:44:19.989893Z","caller":"traceutil/trace.go:171","msg":"trace[1509952271] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2166; }","duration":"224.056575ms","start":"2026-04-16T11:44:19.765295Z","end":"2026-04-16T11:44:19.989351Z","steps":["trace[1509952271] 'agreement among raft nodes before linearized reading' (duration: 223.991762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.989244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.472772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.990138Z","caller":"traceutil/trace.go:171","msg":"trace[694805481] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2166; }","duration":"271.377052ms","start":"2026-04-16T11:44:19.718748Z","end":"2026-04-16T11:44:19.990125Z","steps":["trace[694805481] 'agreement among raft nodes before linearized reading' (duration: 270.453081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.99049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.459042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.990526Z","caller":"traceutil/trace.go:171","msg":"trace[1596971998] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2166; }","duration":"225.518596ms","start":"2026-04-16T11:44:19.764999Z","end":"2026-04-16T11:44:19.990518Z","steps":["trace[1596971998] 'agreement among raft nodes before linearized reading' (duration: 225.457272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.99058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.546834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.990627Z","caller":"traceutil/trace.go:171","msg":"trace[1115449371] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2166; }","duration":"308.610566ms","start":"2026-04-16T11:44:19.682005Z","end":"2026-04-16T11:44:19.990616Z","steps":["trace[1115449371] 'agreement among raft nodes before linearized reading' (duration: 308.537192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.990699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.681994Z","time spent":"308.66121ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-04-16T11:44:19.990789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.029338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.990875Z","caller":"traceutil/trace.go:171","msg":"trace[1901893215] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2166; }","duration":"240.129194ms","start":"2026-04-16T11:44:19.750737Z","end":"2026-04-16T11:44:19.990866Z","steps":["trace[1901893215] 'agreement among raft nodes before linearized reading' (duration: 240.033479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.991117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.762083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.991168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.702424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.991214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.255735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.991251Z","caller":"traceutil/trace.go:171","msg":"trace[808006080] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2166; }","duration":"286.307637ms","start":"2026-04-16T11:44:19.704934Z","end":"2026-04-16T11:44:19.991241Z","steps":["trace[808006080] 'agreement among raft nodes before linearized reading' (duration: 286.254794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.991467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.024206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.991507Z","caller":"traceutil/trace.go:171","msg":"trace[214108797] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2166; }","duration":"287.066659ms","start":"2026-04-16T11:44:19.704431Z","end":"2026-04-16T11:44:19.991498Z","steps":["trace[214108797] 'agreement among raft nodes before linearized reading' (duration: 287.007106ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.989348Z","caller":"traceutil/trace.go:171","msg":"trace[875178975] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2166; }","duration":"345.354105ms","start":"2026-04-16T11:44:19.643955Z","end":"2026-04-16T11:44:19.989309Z","steps":["trace[875178975] 'agreement among raft nodes before linearized reading' (duration: 345.234419ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.992181Z","caller":"traceutil/trace.go:171","msg":"trace[1971434637] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2166; }","duration":"241.686859ms","start":"2026-04-16T11:44:19.750437Z","end":"2026-04-16T11:44:19.992124Z","steps":["trace[1971434637] 'agreement among raft nodes before linearized reading' (duration: 240.715456ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.991166Z","caller":"traceutil/trace.go:171","msg":"trace[1039694186] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:2166; }","duration":"286.829296ms","start":"2026-04-16T11:44:19.704325Z","end":"2026-04-16T11:44:19.991154Z","steps":["trace[1039694186] 'agreement among raft nodes before linearized reading' (duration: 286.741321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.992053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.643946Z","time spent":"348.086004ms","remote":"127.0.0.1:53220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T11:44:19.991558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.360706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.993289Z","caller":"traceutil/trace.go:171","msg":"trace[702960077] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2166; }","duration":"274.089729ms","start":"2026-04-16T11:44:19.719167Z","end":"2026-04-16T11:44:19.993257Z","steps":["trace[702960077] 'agreement among raft nodes before linearized reading' (duration: 272.338014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.993417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.70022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.993447Z","caller":"traceutil/trace.go:171","msg":"trace[383559135] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2166; }","duration":"310.741964ms","start":"2026-04-16T11:44:19.682698Z","end":"2026-04-16T11:44:19.99344Z","steps":["trace[383559135] 'agreement among raft nodes before linearized reading' (duration: 310.697982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.993469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.682688Z","time spent":"310.775535ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:500 "} {"level":"warn","ts":"2026-04-16T11:44:19.991136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.592559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.993647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.857781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.99367Z","caller":"traceutil/trace.go:171","msg":"trace[508122960] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2166; }","duration":"332.899944ms","start":"2026-04-16T11:44:19.660763Z","end":"2026-04-16T11:44:19.993663Z","steps":["trace[508122960] 'agreement among raft nodes before linearized reading' (duration: 332.865332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.993687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.660751Z","time spent":"332.931316ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-04-16T11:44:19.993742Z","caller":"traceutil/trace.go:171","msg":"trace[1689835452] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:2166; }","duration":"243.015851ms","start":"2026-04-16T11:44:19.750497Z","end":"2026-04-16T11:44:19.993513Z","steps":["trace[1689835452] 'agreement among raft nodes before linearized reading' (duration: 240.610981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.993781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.434999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.993798Z","caller":"traceutil/trace.go:171","msg":"trace[1328968916] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2166; }","duration":"332.508372ms","start":"2026-04-16T11:44:19.661283Z","end":"2026-04-16T11:44:19.993792Z","steps":["trace[1328968916] 'agreement among raft nodes before linearized reading' (duration: 332.4837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.993817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.661251Z","time spent":"332.561316ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:500 "} {"level":"warn","ts":"2026-04-16T11:44:19.99394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.948096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.993961Z","caller":"traceutil/trace.go:171","msg":"trace[927517173] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2166; }","duration":"333.005359ms","start":"2026-04-16T11:44:19.66095Z","end":"2026-04-16T11:44:19.993955Z","steps":["trace[927517173] 'agreement among raft nodes before linearized reading' (duration: 332.971157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.993976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.660941Z","time spent":"333.030711ms","remote":"127.0.0.1:53226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T11:44:19.994119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.03313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7db6edcf-9f21-465a-9af2-8e34fd2b2ecb\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-16T11:44:19.994191Z","caller":"traceutil/trace.go:171","msg":"trace[1559406128] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7db6edcf-9f21-465a-9af2-8e34fd2b2ecb; range_end:; response_count:1; response_revision:2166; }","duration":"249.125196ms","start":"2026-04-16T11:44:19.745053Z","end":"2026-04-16T11:44:19.994178Z","steps":["trace[1559406128] 'agreement among raft nodes before linearized reading' (duration: 248.746445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.994242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.922934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T11:44:19.994356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.119148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.994411Z","caller":"traceutil/trace.go:171","msg":"trace[2062206247] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2166; }","duration":"350.103214ms","start":"2026-04-16T11:44:19.644297Z","end":"2026-04-16T11:44:19.994401Z","steps":["trace[2062206247] 'agreement among raft nodes before linearized reading' (duration: 349.920194ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:19.994383Z","caller":"traceutil/trace.go:171","msg":"trace[1052365046] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2166; }","duration":"312.184993ms","start":"2026-04-16T11:44:19.682191Z","end":"2026-04-16T11:44:19.994376Z","steps":["trace[1052365046] 'agreement among raft nodes before linearized reading' (duration: 312.141451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.994448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.644289Z","time spent":"350.149826ms","remote":"127.0.0.1:53220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-04-16T11:44:19.994455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.682058Z","time spent":"312.389924ms","remote":"127.0.0.1:53240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" limit:10000 "} {"level":"warn","ts":"2026-04-16T11:44:19.994616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.025582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.994663Z","caller":"traceutil/trace.go:171","msg":"trace[581309354] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2166; }","duration":"258.132848ms","start":"2026-04-16T11:44:19.736518Z","end":"2026-04-16T11:44:19.994651Z","steps":["trace[581309354] 'agreement among raft nodes before linearized reading' (duration: 258.036613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.993966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.634254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.994942Z","caller":"traceutil/trace.go:171","msg":"trace[249739830] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:2166; }","duration":"349.643209ms","start":"2026-04-16T11:44:19.645289Z","end":"2026-04-16T11:44:19.994932Z","steps":["trace[249739830] 'agreement among raft nodes before linearized reading' (duration: 348.649784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.99494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.672377ms","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-04-16T11:44:19.994975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:19.645269Z","time spent":"349.694053ms","remote":"127.0.0.1:53220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" limit:500 "} {"level":"info","ts":"2026-04-16T11:44:19.994976Z","caller":"traceutil/trace.go:171","msg":"trace[686511583] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2166; }","duration":"258.724271ms","start":"2026-04-16T11:44:19.736241Z","end":"2026-04-16T11:44:19.994965Z","steps":["trace[686511583] 'agreement among raft nodes before linearized reading' (duration: 258.661137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.995066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.843066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.995086Z","caller":"traceutil/trace.go:171","msg":"trace[1156361558] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:2166; }","duration":"258.888519ms","start":"2026-04-16T11:44:19.736191Z","end":"2026-04-16T11:44:19.995079Z","steps":["trace[1156361558] 'agreement among raft nodes before linearized reading' (duration: 258.850447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:19.990754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.818791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:19.995504Z","caller":"traceutil/trace.go:171","msg":"trace[438070324] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2166; }","duration":"230.574242ms","start":"2026-04-16T11:44:19.764913Z","end":"2026-04-16T11:44:19.995487Z","steps":["trace[438070324] 'agreement among raft nodes before linearized reading' (duration: 225.748838ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:20.185184Z","caller":"traceutil/trace.go:171","msg":"trace[844437332] linearizableReadLoop","detail":"{readStateIndex:2248; appliedIndex:2246; }","duration":"122.511226ms","start":"2026-04-16T11:44:20.062652Z","end":"2026-04-16T11:44:20.185163Z","steps":["trace[844437332] 'read index received' (duration: 21.429923ms)","trace[844437332] 'applied index is now lower than readState.Index' (duration: 101.080253ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:20.185319Z","caller":"traceutil/trace.go:171","msg":"trace[1165541833] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"128.80579ms","start":"2026-04-16T11:44:20.056499Z","end":"2026-04-16T11:44:20.185305Z","steps":["trace[1165541833] 'process raft request' (duration: 86.546957ms)","trace[1165541833] 'compare' (duration: 41.965067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:20.185608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.926408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T11:44:20.185685Z","caller":"traceutil/trace.go:171","msg":"trace[1340710857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2168; }","duration":"123.035163ms","start":"2026-04-16T11:44:20.062633Z","end":"2026-04-16T11:44:20.185668Z","steps":["trace[1340710857] 'agreement among raft nodes before linearized reading' (duration: 122.836652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:20.235016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.058796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T11:44:20.23509Z","caller":"traceutil/trace.go:171","msg":"trace[388424349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2171; }","duration":"172.159722ms","start":"2026-04-16T11:44:20.062912Z","end":"2026-04-16T11:44:20.235072Z","steps":["trace[388424349] 'agreement among raft nodes before linearized reading' (duration: 171.996743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:20.235249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.353973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:20.235282Z","caller":"traceutil/trace.go:171","msg":"trace[476738459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2171; }","duration":"155.407175ms","start":"2026-04-16T11:44:20.079866Z","end":"2026-04-16T11:44:20.235273Z","steps":["trace[476738459] 'agreement among raft nodes before linearized reading' (duration: 155.353352ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:41.718065Z","caller":"traceutil/trace.go:171","msg":"trace[541762513] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"127.427384ms","start":"2026-04-16T11:44:41.590608Z","end":"2026-04-16T11:44:41.718036Z","steps":["trace[541762513] 'process raft request' (duration: 127.128277ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:51.945639Z","caller":"traceutil/trace.go:171","msg":"trace[522611737] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"211.873932ms","start":"2026-04-16T11:44:51.733717Z","end":"2026-04-16T11:44:51.945591Z","steps":["trace[522611737] 'process raft request' (duration: 125.066123ms)","trace[522611737] 'compare' (duration: 86.47109ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:51.990345Z","caller":"traceutil/trace.go:171","msg":"trace[129438945] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"137.912139ms","start":"2026-04-16T11:44:51.852407Z","end":"2026-04-16T11:44:51.990319Z","steps":["trace[129438945] 'process raft request' (duration: 137.763931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:53.202617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.820197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:53.202713Z","caller":"traceutil/trace.go:171","msg":"trace[1542200776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2452; }","duration":"122.975126ms","start":"2026-04-16T11:44:53.079714Z","end":"2026-04-16T11:44:53.202689Z","steps":["trace[1542200776] 'range keys from in-memory index tree' (duration: 122.70395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:54.168277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.333296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-04-16T11:44:54.168347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.440608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11985"} {"level":"info","ts":"2026-04-16T11:44:54.168368Z","caller":"traceutil/trace.go:171","msg":"trace[138114003] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2455; }","duration":"426.475974ms","start":"2026-04-16T11:44:53.74187Z","end":"2026-04-16T11:44:54.168346Z","steps":["trace[138114003] 'range keys from in-memory index tree' (duration: 426.170447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:54.168414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:53.74185Z","time spent":"426.555059ms","remote":"127.0.0.1:54788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-16T11:44:54.168413Z","caller":"traceutil/trace.go:171","msg":"trace[892940180] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2455; }","duration":"461.010971ms","start":"2026-04-16T11:44:53.707385Z","end":"2026-04-16T11:44:54.168396Z","steps":["trace[892940180] 'range keys from in-memory index tree' (duration: 460.286269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:54.168453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:53.70737Z","time spent":"461.074404ms","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12009,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-16T11:44:54.168488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.105561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a6d3b67e739c9e\" ","response":"range_response_count:1 size:765"} {"level":"warn","ts":"2026-04-16T11:44:54.168281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.023697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T11:44:54.168541Z","caller":"traceutil/trace.go:171","msg":"trace[379439795] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a6d3b67e739c9e; range_end:; response_count:1; response_revision:2455; }","duration":"352.193907ms","start":"2026-04-16T11:44:53.816332Z","end":"2026-04-16T11:44:54.168526Z","steps":["trace[379439795] 'range keys from in-memory index tree' (duration: 351.979414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:54.168575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:53.816308Z","time spent":"352.25892ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":789,"request content":"key:\"/registry/events/openstack/valkey-node-0.18a6d3b67e739c9e\" "} {"level":"info","ts":"2026-04-16T11:44:54.168612Z","caller":"traceutil/trace.go:171","msg":"trace[2057343114] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2455; }","duration":"337.375527ms","start":"2026-04-16T11:44:53.831184Z","end":"2026-04-16T11:44:54.16856Z","steps":["trace[2057343114] 'count revisions from in-memory index tree' (duration: 336.951223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:54.168762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:53.831174Z","time spent":"337.528607ms","remote":"127.0.0.1:55022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-16T11:44:54.168963Z","caller":"traceutil/trace.go:171","msg":"trace[1950509652] linearizableReadLoop","detail":"{readStateIndex:2545; appliedIndex:2544; }","duration":"136.768342ms","start":"2026-04-16T11:44:54.032167Z","end":"2026-04-16T11:44:54.168935Z","steps":["trace[1950509652] 'read index received' (duration: 114.061125ms)","trace[1950509652] 'applied index is now lower than readState.Index' (duration: 22.705717ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:54.1691Z","caller":"traceutil/trace.go:171","msg":"trace[922862160] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"172.232793ms","start":"2026-04-16T11:44:53.996851Z","end":"2026-04-16T11:44:54.169084Z","steps":["trace[922862160] 'process raft request' (duration: 149.366107ms)","trace[922862160] 'compare' (duration: 21.728032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:54.169305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.138375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T11:44:54.169426Z","caller":"traceutil/trace.go:171","msg":"trace[739089383] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2456; }","duration":"137.22378ms","start":"2026-04-16T11:44:54.032118Z","end":"2026-04-16T11:44:54.169342Z","steps":["trace[739089383] 'agreement among raft nodes before linearized reading' (duration: 137.089602ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:54.342721Z","caller":"traceutil/trace.go:171","msg":"trace[1947380786] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"193.216703ms","start":"2026-04-16T11:44:54.149477Z","end":"2026-04-16T11:44:54.342694Z","steps":["trace[1947380786] 'process raft request' (duration: 192.956548ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:54.342856Z","caller":"traceutil/trace.go:171","msg":"trace[302378525] transaction","detail":"{read_only:false; response_revision:2460; number_of_response:1; }","duration":"171.318511ms","start":"2026-04-16T11:44:54.171507Z","end":"2026-04-16T11:44:54.342826Z","steps":["trace[302378525] 'process raft request' (duration: 171.066017ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:54.343374Z","caller":"traceutil/trace.go:171","msg":"trace[1218994251] linearizableReadLoop","detail":"{readStateIndex:2548; appliedIndex:2545; }","duration":"174.30196ms","start":"2026-04-16T11:44:54.169049Z","end":"2026-04-16T11:44:54.343351Z","steps":["trace[1218994251] 'read index received' (duration: 63.4997ms)","trace[1218994251] 'applied index is now lower than readState.Index' (duration: 110.80129ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:54.343434Z","caller":"traceutil/trace.go:171","msg":"trace[1628739053] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"163.419444ms","start":"2026-04-16T11:44:54.179989Z","end":"2026-04-16T11:44:54.343408Z","steps":["trace[1628739053] 'process raft request' (duration: 163.239743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:54.343477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.654877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:54.343537Z","caller":"traceutil/trace.go:171","msg":"trace[378043370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2461; }","duration":"263.757552ms","start":"2026-04-16T11:44:54.079763Z","end":"2026-04-16T11:44:54.34352Z","steps":["trace[378043370] 'agreement among raft nodes before linearized reading' (duration: 263.656347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:54.344523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.719971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7db6edcf-9f21-465a-9af2-8e34fd2b2ecb\" ","response":"range_response_count:1 size:1303"} {"level":"warn","ts":"2026-04-16T11:44:54.344556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.142794ms","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-04-16T11:44:54.344576Z","caller":"traceutil/trace.go:171","msg":"trace[1358646131] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7db6edcf-9f21-465a-9af2-8e34fd2b2ecb; range_end:; response_count:1; response_revision:2461; }","duration":"172.802405ms","start":"2026-04-16T11:44:54.171756Z","end":"2026-04-16T11:44:54.344558Z","steps":["trace[1358646131] 'agreement among raft nodes before linearized reading' (duration: 172.508128ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:54.344598Z","caller":"traceutil/trace.go:171","msg":"trace[1310114613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2461; }","duration":"116.210707ms","start":"2026-04-16T11:44:54.228376Z","end":"2026-04-16T11:44:54.344587Z","steps":["trace[1310114613] 'agreement among raft nodes before linearized reading' (duration: 116.088431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.037824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.149769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:44:55.038461Z","caller":"traceutil/trace.go:171","msg":"trace[1685972652] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"688.626268ms","start":"2026-04-16T11:44:54.349787Z","end":"2026-04-16T11:44:55.038413Z","steps":["trace[1685972652] 'process raft request' (duration: 83.805331ms)","trace[1685972652] 'compare' (duration: 603.894694ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:55.038541Z","caller":"traceutil/trace.go:171","msg":"trace[407054531] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"688.520042ms","start":"2026-04-16T11:44:54.349995Z","end":"2026-04-16T11:44:55.038516Z","steps":["trace[407054531] 'process raft request' (duration: 687.927529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.038808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:54.349981Z","time spent":"688.738603ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:44:55.038857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:54.349767Z","time spent":"688.794207ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:55.038892Z","caller":"traceutil/trace.go:171","msg":"trace[2037918212] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"675.580138ms","start":"2026-04-16T11:44:54.363295Z","end":"2026-04-16T11:44:55.038875Z","steps":["trace[2037918212] 'process raft request' (duration: 674.697618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.038978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:54.363277Z","time spent":"675.657402ms","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12367,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:55.039208Z","caller":"traceutil/trace.go:171","msg":"trace[52941149] linearizableReadLoop","detail":"{readStateIndex:2553; appliedIndex:2550; }","duration":"656.096853ms","start":"2026-04-16T11:44:54.382531Z","end":"2026-04-16T11:44:55.038628Z","steps":["trace[52941149] 'read index received' (duration: 51.16194ms)","trace[52941149] 'applied index is now lower than readState.Index' (duration: 604.934003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:55.039364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.846706ms","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-04-16T11:44:55.039399Z","caller":"traceutil/trace.go:171","msg":"trace[272784607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2464; }","duration":"656.936061ms","start":"2026-04-16T11:44:54.382454Z","end":"2026-04-16T11:44:55.03939Z","steps":["trace[272784607] 'agreement among raft nodes before linearized reading' (duration: 656.809404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.039428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:54.382426Z","time spent":"656.995505ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T11:44:55.03976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.642711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T11:44:55.0398Z","caller":"traceutil/trace.go:171","msg":"trace[1353678165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2464; }","duration":"642.734356ms","start":"2026-04-16T11:44:54.397056Z","end":"2026-04-16T11:44:55.039791Z","steps":["trace[1353678165] 'agreement among raft nodes before linearized reading' (duration: 642.63791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.039828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:54.397029Z","time spent":"642.792778ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T11:44:55.040151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.650711ms","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-04-16T11:44:55.040189Z","caller":"traceutil/trace.go:171","msg":"trace[1146365183] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2464; }","duration":"138.750307ms","start":"2026-04-16T11:44:54.901431Z","end":"2026-04-16T11:44:55.040181Z","steps":["trace[1146365183] 'agreement among raft nodes before linearized reading' (duration: 138.679023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.040439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.375142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T11:44:55.040537Z","caller":"traceutil/trace.go:171","msg":"trace[1557138814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2464; }","duration":"622.5132ms","start":"2026-04-16T11:44:54.418007Z","end":"2026-04-16T11:44:55.04052Z","steps":["trace[1557138814] 'agreement among raft nodes before linearized reading' (duration: 622.153369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.040629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:54.41798Z","time spent":"622.637317ms","remote":"127.0.0.1:54870","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-04-16T11:44:55.040698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.321855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-16T11:44:55.040741Z","caller":"traceutil/trace.go:171","msg":"trace[2129497547] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2464; }","duration":"285.402671ms","start":"2026-04-16T11:44:54.755327Z","end":"2026-04-16T11:44:55.04073Z","steps":["trace[2129497547] 'agreement among raft nodes before linearized reading' (duration: 285.300724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.040963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.614063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-16T11:44:55.041073Z","caller":"traceutil/trace.go:171","msg":"trace[1700818261] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2464; }","duration":"517.765202ms","start":"2026-04-16T11:44:54.523284Z","end":"2026-04-16T11:44:55.04105Z","steps":["trace[1700818261] 'agreement among raft nodes before linearized reading' (duration: 517.599383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.041257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:54.523256Z","time spent":"517.946312ms","remote":"127.0.0.1:53160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-16T11:44:55.844289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.581357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:44:55.844541Z","caller":"traceutil/trace.go:171","msg":"trace[1109910559] linearizableReadLoop","detail":"{readStateIndex:2557; appliedIndex:2553; }","duration":"763.710635ms","start":"2026-04-16T11:44:55.080812Z","end":"2026-04-16T11:44:55.844523Z","steps":["trace[1109910559] 'read index received' (duration: 384.80539ms)","trace[1109910559] 'applied index is now lower than readState.Index' (duration: 378.904365ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:44:55.844641Z","caller":"traceutil/trace.go:171","msg":"trace[989092798] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"798.51036ms","start":"2026-04-16T11:44:55.04612Z","end":"2026-04-16T11:44:55.84463Z","steps":["trace[989092798] 'process raft request' (duration: 798.33473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.844704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:55.046102Z","time spent":"798.568863ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:55.84493Z","caller":"traceutil/trace.go:171","msg":"trace[868113061] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"798.776955ms","start":"2026-04-16T11:44:55.046143Z","end":"2026-04-16T11:44:55.84492Z","steps":["trace[868113061] 'process raft request' (duration: 798.342281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.844989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:55.045985Z","time spent":"798.975726ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:44:55.845174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.362533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:44:55.845212Z","caller":"traceutil/trace.go:171","msg":"trace[516542275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2468; }","duration":"764.420456ms","start":"2026-04-16T11:44:55.080781Z","end":"2026-04-16T11:44:55.845202Z","steps":["trace[516542275] 'agreement among raft nodes before linearized reading' (duration: 764.336001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.845242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:55.080773Z","time spent":"764.461418ms","remote":"127.0.0.1:54644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T11:44:55.845238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.452948ms","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-04-16T11:44:55.845312Z","caller":"traceutil/trace.go:171","msg":"trace[1211143215] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2468; }","duration":"146.585386ms","start":"2026-04-16T11:44:55.698713Z","end":"2026-04-16T11:44:55.845299Z","steps":["trace[1211143215] 'agreement among raft nodes before linearized reading' (duration: 146.462609ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:44:55.845505Z","caller":"traceutil/trace.go:171","msg":"trace[1627677939] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"799.900689ms","start":"2026-04-16T11:44:55.045577Z","end":"2026-04-16T11:44:55.845478Z","steps":["trace[1627677939] 'process raft request' (duration: 420.04314ms)","trace[1627677939] 'compare' (duration: 378.46605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:44:55.845661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:55.045562Z","time spent":"800.019034ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:44:55.84585Z","caller":"traceutil/trace.go:171","msg":"trace[98755788] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"798.495029ms","start":"2026-04-16T11:44:55.046119Z","end":"2026-04-16T11:44:55.844614Z","steps":["trace[98755788] 'process raft request' (duration: 798.277957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:44:55.846033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:44:55.046111Z","time spent":"799.780812ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:46:18.620745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.485163ms","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-04-16T11:46:18.620844Z","caller":"traceutil/trace.go:171","msg":"trace[140713527] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2968; }","duration":"106.675252ms","start":"2026-04-16T11:46:18.514151Z","end":"2026-04-16T11:46:18.620826Z","steps":["trace[140713527] 'count revisions from in-memory index tree' (duration: 106.367533ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:46:55.072114Z","caller":"traceutil/trace.go:171","msg":"trace[405117803] transaction","detail":"{read_only:false; response_revision:3169; number_of_response:1; }","duration":"279.215781ms","start":"2026-04-16T11:46:54.792869Z","end":"2026-04-16T11:46:55.072085Z","steps":["trace[405117803] 'process raft request' (duration: 240.64002ms)","trace[405117803] 'compare' (duration: 37.990192ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:46:55.072747Z","caller":"traceutil/trace.go:171","msg":"trace[433967785] transaction","detail":"{read_only:false; response_revision:3170; number_of_response:1; }","duration":"277.989554ms","start":"2026-04-16T11:46:54.794744Z","end":"2026-04-16T11:46:55.072733Z","steps":["trace[433967785] 'process raft request' (duration: 276.902978ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:30.006832Z","caller":"traceutil/trace.go:171","msg":"trace[1524517350] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"102.205366ms","start":"2026-04-16T11:50:29.904596Z","end":"2026-04-16T11:50:30.006801Z","steps":["trace[1524517350] 'process raft request' (duration: 102.060419ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:42.016681Z","caller":"traceutil/trace.go:171","msg":"trace[1500958709] transaction","detail":"{read_only:false; response_revision:5366; number_of_response:1; }","duration":"113.891293ms","start":"2026-04-16T11:50:41.902759Z","end":"2026-04-16T11:50:42.01665Z","steps":["trace[1500958709] 'process raft request' (duration: 113.309618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:43.031615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.97071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:43.032216Z","caller":"traceutil/trace.go:171","msg":"trace[1394293367] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"292.197224ms","start":"2026-04-16T11:50:42.739997Z","end":"2026-04-16T11:50:43.032194Z","steps":["trace[1394293367] 'process raft request' (duration: 292.068588ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:43.032463Z","caller":"traceutil/trace.go:171","msg":"trace[508671731] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"294.84228ms","start":"2026-04-16T11:50:42.737608Z","end":"2026-04-16T11:50:43.03245Z","steps":["trace[508671731] 'process raft request' (duration: 72.883099ms)","trace[508671731] 'compare' (duration: 220.738288ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:43.032586Z","caller":"traceutil/trace.go:171","msg":"trace[1436157110] linearizableReadLoop","detail":"{readStateIndex:5570; appliedIndex:5569; }","duration":"292.633843ms","start":"2026-04-16T11:50:42.739941Z","end":"2026-04-16T11:50:43.032574Z","steps":["trace[1436157110] 'read index received' (duration: 70.44209ms)","trace[1436157110] 'applied index is now lower than readState.Index' (duration: 222.190333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:43.032892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.936846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-nzmcf-tls\" ","response":"range_response_count:1 size:2129"} {"level":"info","ts":"2026-04-16T11:50:43.032921Z","caller":"traceutil/trace.go:171","msg":"trace[2013698706] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-nzmcf-tls; range_end:; response_count:1; response_revision:5380; }","duration":"293.01986ms","start":"2026-04-16T11:50:42.739892Z","end":"2026-04-16T11:50:43.032912Z","steps":["trace[2013698706] 'agreement among raft nodes before linearized reading' (duration: 292.834882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:43.033142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.738434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-16T11:50:43.033167Z","caller":"traceutil/trace.go:171","msg":"trace[183350525] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5380; }","duration":"279.781926ms","start":"2026-04-16T11:50:42.753378Z","end":"2026-04-16T11:50:43.03316Z","steps":["trace[183350525] 'agreement among raft nodes before linearized reading' (duration: 279.701062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:43.034506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.751135ms","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-04-16T11:50:43.036379Z","caller":"traceutil/trace.go:171","msg":"trace[355305033] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:5380; }","duration":"159.896032ms","start":"2026-04-16T11:50:42.874674Z","end":"2026-04-16T11:50:43.03457Z","steps":["trace[355305033] 'agreement among raft nodes before linearized reading' (duration: 159.775537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:44.542184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.654235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:44.5423Z","caller":"traceutil/trace.go:171","msg":"trace[521766475] linearizableReadLoop","detail":"{readStateIndex:5610; appliedIndex:5609; }","duration":"715.311484ms","start":"2026-04-16T11:50:43.826971Z","end":"2026-04-16T11:50:44.542283Z","steps":["trace[521766475] 'read index received' (duration: 48.371598ms)","trace[521766475] 'applied index is now lower than readState.Index' (duration: 666.938676ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:44.54249Z","caller":"traceutil/trace.go:171","msg":"trace[856849225] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"717.331104ms","start":"2026-04-16T11:50:43.825145Z","end":"2026-04-16T11:50:44.542476Z","steps":["trace[856849225] 'process raft request' (duration: 50.269252ms)","trace[856849225] 'compare' (duration: 666.503768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:44.542549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:43.825136Z","time spent":"717.380217ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:50:44.542802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.827607ms","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-04-16T11:50:44.542833Z","caller":"traceutil/trace.go:171","msg":"trace[387462309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5418; }","duration":"715.88621ms","start":"2026-04-16T11:50:43.826935Z","end":"2026-04-16T11:50:44.542822Z","steps":["trace[387462309] 'agreement among raft nodes before linearized reading' (duration: 715.776205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:44.54286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:43.826919Z","time spent":"715.935092ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T11:50:44.557799Z","caller":"traceutil/trace.go:171","msg":"trace[1340100270] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"533.252957ms","start":"2026-04-16T11:50:44.024527Z","end":"2026-04-16T11:50:44.55778Z","steps":["trace[1340100270] 'process raft request' (duration: 533.102591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:44.558736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:44.024478Z","time spent":"533.448047ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:50:44.559348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.681566ms","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-04-16T11:50:44.559501Z","caller":"traceutil/trace.go:171","msg":"trace[1192204409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5421; }","duration":"729.086866ms","start":"2026-04-16T11:50:43.830395Z","end":"2026-04-16T11:50:44.559482Z","steps":["trace[1192204409] 'agreement among raft nodes before linearized reading' (duration: 728.439947ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:44.559525Z","caller":"traceutil/trace.go:171","msg":"trace[1944480169] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"507.349241ms","start":"2026-04-16T11:50:44.05215Z","end":"2026-04-16T11:50:44.559499Z","steps":["trace[1944480169] 'process raft request' (duration: 506.523504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:44.559556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:43.83039Z","time spent":"729.153438ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T11:50:44.559624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:44.052131Z","time spent":"507.436406ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:50:44.560129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.816518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T11:50:44.560179Z","caller":"traceutil/trace.go:171","msg":"trace[1237893840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5421; }","duration":"480.908902ms","start":"2026-04-16T11:50:44.079259Z","end":"2026-04-16T11:50:44.560168Z","steps":["trace[1237893840] 'agreement among raft nodes before linearized reading' (duration: 480.819968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:44.560217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:44.079235Z","time spent":"480.975484ms","remote":"127.0.0.1:54644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T11:50:44.560316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.012372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1ffd37b4-750c-41b7-a719-61309c2db5e7\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-04-16T11:50:44.560628Z","caller":"traceutil/trace.go:171","msg":"trace[186930236] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1ffd37b4-750c-41b7-a719-61309c2db5e7; range_end:; response_count:1; response_revision:5421; }","duration":"520.324166ms","start":"2026-04-16T11:50:44.04014Z","end":"2026-04-16T11:50:44.560464Z","steps":["trace[186930236] 'agreement among raft nodes before linearized reading' (duration: 519.332152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:44.560824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:44.040122Z","time spent":"520.683331ms","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4091,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1ffd37b4-750c-41b7-a719-61309c2db5e7\" "} {"level":"warn","ts":"2026-04-16T11:50:44.560652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.882775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2193158"} {"level":"info","ts":"2026-04-16T11:50:44.561381Z","caller":"traceutil/trace.go:171","msg":"trace[483983931] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5421; }","duration":"648.552745ms","start":"2026-04-16T11:50:43.912726Z","end":"2026-04-16T11:50:44.561279Z","steps":["trace[483983931] 'agreement among raft nodes before linearized reading' (duration: 646.984316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:44.561617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:43.912708Z","time spent":"648.875638ms","remote":"127.0.0.1:54718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2193182,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-04-16T11:50:45.949904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.06884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:45.950025Z","caller":"traceutil/trace.go:171","msg":"trace[1283174021] linearizableReadLoop","detail":"{readStateIndex:5646; appliedIndex:5645; }","duration":"223.411467ms","start":"2026-04-16T11:50:45.726594Z","end":"2026-04-16T11:50:45.950005Z","steps":["trace[1283174021] 'read index received' (duration: 35.025262ms)","trace[1283174021] 'applied index is now lower than readState.Index' (duration: 188.384665ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:45.950133Z","caller":"traceutil/trace.go:171","msg":"trace[2046586663] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"233.915512ms","start":"2026-04-16T11:50:45.716198Z","end":"2026-04-16T11:50:45.950113Z","steps":["trace[2046586663] 'process raft request' (duration: 45.508366ms)","trace[2046586663] 'compare' (duration: 187.848711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:45.950225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.237489ms","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-04-16T11:50:45.950314Z","caller":"traceutil/trace.go:171","msg":"trace[1099028479] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5453; }","duration":"174.362464ms","start":"2026-04-16T11:50:45.775927Z","end":"2026-04-16T11:50:45.950289Z","steps":["trace[1099028479] 'agreement among raft nodes before linearized reading' (duration: 174.210987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:45.950463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.003633ms","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-04-16T11:50:45.950667Z","caller":"traceutil/trace.go:171","msg":"trace[1331265192] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5453; }","duration":"124.247373ms","start":"2026-04-16T11:50:45.826395Z","end":"2026-04-16T11:50:45.950643Z","steps":["trace[1331265192] 'agreement among raft nodes before linearized reading' (duration: 123.821724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:45.950762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.16212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-nzmcf.18a6d40a4a59bd56\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-04-16T11:50:45.950825Z","caller":"traceutil/trace.go:171","msg":"trace[1300951171] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-nzmcf.18a6d40a4a59bd56; range_end:; response_count:1; response_revision:5453; }","duration":"224.254915ms","start":"2026-04-16T11:50:45.726553Z","end":"2026-04-16T11:50:45.950808Z","steps":["trace[1300951171] 'agreement among raft nodes before linearized reading' (duration: 224.063007ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:46.139407Z","caller":"traceutil/trace.go:171","msg":"trace[1590436287] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"126.349643ms","start":"2026-04-16T11:50:46.01303Z","end":"2026-04-16T11:50:46.13938Z","steps":["trace[1590436287] 'process raft request' (duration: 126.166144ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:46.139436Z","caller":"traceutil/trace.go:171","msg":"trace[976771791] transaction","detail":"{read_only:false; response_revision:5456; number_of_response:1; }","duration":"159.202748ms","start":"2026-04-16T11:50:45.9802Z","end":"2026-04-16T11:50:46.139403Z","steps":["trace[976771791] 'process raft request' (duration: 103.283218ms)","trace[976771791] 'compare' (duration: 55.506903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:50.479981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.601978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:50.480133Z","caller":"traceutil/trace.go:171","msg":"trace[1227066859] linearizableReadLoop","detail":"{readStateIndex:5681; appliedIndex:5680; }","duration":"515.543593ms","start":"2026-04-16T11:50:49.964561Z","end":"2026-04-16T11:50:50.480104Z","steps":["trace[1227066859] 'read index received' (duration: 202.673732ms)","trace[1227066859] 'applied index is now lower than readState.Index' (duration: 312.867561ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:50.480534Z","caller":"traceutil/trace.go:171","msg":"trace[201948856] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"593.589155ms","start":"2026-04-16T11:50:49.886858Z","end":"2026-04-16T11:50:50.480447Z","steps":["trace[201948856] 'process raft request' (duration: 280.377759ms)","trace[201948856] 'compare' (duration: 312.265924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:50.480543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.604622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T11:50:50.480633Z","caller":"traceutil/trace.go:171","msg":"trace[250826992] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:5487; }","duration":"105.779231ms","start":"2026-04-16T11:50:50.374837Z","end":"2026-04-16T11:50:50.480616Z","steps":["trace[250826992] 'agreement among raft nodes before linearized reading' (duration: 105.604293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:50.480789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.195161ms","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-04-16T11:50:50.481026Z","caller":"traceutil/trace.go:171","msg":"trace[1187450053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5487; }","duration":"516.491256ms","start":"2026-04-16T11:50:49.9645Z","end":"2026-04-16T11:50:50.480991Z","steps":["trace[1187450053] 'agreement among raft nodes before linearized reading' (duration: 515.840096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:50.481187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:49.964481Z","time spent":"516.687483ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T11:50:50.480866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:49.886838Z","time spent":"593.781623ms","remote":"127.0.0.1:54880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T11:50:50.756755Z","caller":"traceutil/trace.go:171","msg":"trace[463814991] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"270.423671ms","start":"2026-04-16T11:50:50.486292Z","end":"2026-04-16T11:50:50.756716Z","steps":["trace[463814991] 'process raft request' (duration: 229.854054ms)","trace[463814991] 'compare' (duration: 40.246483ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:50:50.75731Z","caller":"traceutil/trace.go:171","msg":"trace[13681389] linearizableReadLoop","detail":"{readStateIndex:5682; appliedIndex:5681; }","duration":"188.177459ms","start":"2026-04-16T11:50:50.569116Z","end":"2026-04-16T11:50:50.757293Z","steps":["trace[13681389] 'read index received' (duration: 146.926432ms)","trace[13681389] 'applied index is now lower than readState.Index' (duration: 41.248356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:50.758318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.187984ms","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-04-16T11:50:50.758379Z","caller":"traceutil/trace.go:171","msg":"trace[741984651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5489; }","duration":"189.293568ms","start":"2026-04-16T11:50:50.569072Z","end":"2026-04-16T11:50:50.758366Z","steps":["trace[741984651] 'agreement among raft nodes before linearized reading' (duration: 188.304154ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:50.758466Z","caller":"traceutil/trace.go:171","msg":"trace[1955303994] transaction","detail":"{read_only:false; response_revision:5490; number_of_response:1; }","duration":"168.243431ms","start":"2026-04-16T11:50:50.590181Z","end":"2026-04-16T11:50:50.758425Z","steps":["trace[1955303994] 'process raft request' (duration: 167.257088ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:50.75885Z","caller":"traceutil/trace.go:171","msg":"trace[1989500941] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"170.066562ms","start":"2026-04-16T11:50:50.588742Z","end":"2026-04-16T11:50:50.758809Z","steps":["trace[1989500941] 'process raft request' (duration: 167.955218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:50.791872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.842213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-16T11:50:50.791915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.988643ms","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-04-16T11:50:50.791954Z","caller":"traceutil/trace.go:171","msg":"trace[1928727849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5491; }","duration":"198.961759ms","start":"2026-04-16T11:50:50.592972Z","end":"2026-04-16T11:50:50.791933Z","steps":["trace[1928727849] 'agreement among raft nodes before linearized reading' (duration: 198.689117ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:50:50.792019Z","caller":"traceutil/trace.go:171","msg":"trace[1732317675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5491; }","duration":"200.129971ms","start":"2026-04-16T11:50:50.591867Z","end":"2026-04-16T11:50:50.791997Z","steps":["trace[1732317675] 'agreement among raft nodes before linearized reading' (duration: 199.769725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:50:51.168604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.158476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T11:50:51.168831Z","caller":"traceutil/trace.go:171","msg":"trace[579257964] transaction","detail":"{read_only:false; response_revision:5495; number_of_response:1; }","duration":"348.432782ms","start":"2026-04-16T11:50:50.820371Z","end":"2026-04-16T11:50:51.168803Z","steps":["trace[579257964] 'process raft request' (duration: 170.961763ms)","trace[579257964] 'compare' (duration: 177.035189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:50:51.16897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:50:50.82034Z","time spent":"348.546177ms","remote":"127.0.0.1:54684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:51:19.404156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278190205448955,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T11:51:19.665782Z","caller":"traceutil/trace.go:171","msg":"trace[904619406] linearizableReadLoop","detail":"{readStateIndex:5941; appliedIndex:5940; }","duration":"762.263038ms","start":"2026-04-16T11:51:18.903475Z","end":"2026-04-16T11:51:19.665738Z","steps":["trace[904619406] 'read index received' (duration: 713.323193ms)","trace[904619406] 'applied index is now lower than readState.Index' (duration: 48.937945ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T11:51:19.665913Z","caller":"traceutil/trace.go:171","msg":"trace[1081281019] transaction","detail":"{read_only:false; response_revision:5739; number_of_response:1; }","duration":"787.253689ms","start":"2026-04-16T11:51:18.878623Z","end":"2026-04-16T11:51:19.665877Z","steps":["trace[1081281019] 'process raft request' (duration: 738.159355ms)","trace[1081281019] 'compare' (duration: 48.730257ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T11:51:19.666269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:18.878607Z","time spent":"787.389955ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T11:51:19.666337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.844205ms","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-04-16T11:51:19.666987Z","caller":"traceutil/trace.go:171","msg":"trace[1897391501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5739; }","duration":"763.549447ms","start":"2026-04-16T11:51:18.903411Z","end":"2026-04-16T11:51:19.66696Z","steps":["trace[1897391501] 'agreement among raft nodes before linearized reading' (duration: 762.553042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.667058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:18.903388Z","time spent":"763.652352ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T11:51:19.673205Z","caller":"traceutil/trace.go:171","msg":"trace[323482276] transaction","detail":"{read_only:false; response_revision:5740; number_of_response:1; }","duration":"196.862263ms","start":"2026-04-16T11:51:19.476317Z","end":"2026-04-16T11:51:19.673179Z","steps":["trace[323482276] 'process raft request' (duration: 196.595761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.6732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.084456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T11:51:19.673243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.119324ms","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-04-16T11:51:19.673268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.797479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-16T11:51:19.673328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.833485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T11:51:19.673346Z","caller":"traceutil/trace.go:171","msg":"trace[2001832165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5740; }","duration":"143.931085ms","start":"2026-04-16T11:51:19.529396Z","end":"2026-04-16T11:51:19.673328Z","steps":["trace[2001832165] 'agreement among raft nodes before linearized reading' (duration: 143.742376ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:51:19.673363Z","caller":"traceutil/trace.go:171","msg":"trace[2099705088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5740; }","duration":"734.195808ms","start":"2026-04-16T11:51:18.939103Z","end":"2026-04-16T11:51:19.673299Z","steps":["trace[2099705088] 'agreement among raft nodes before linearized reading' (duration: 733.951747ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:51:19.673382Z","caller":"traceutil/trace.go:171","msg":"trace[473227479] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5740; }","duration":"355.948151ms","start":"2026-04-16T11:51:19.31742Z","end":"2026-04-16T11:51:19.673368Z","steps":["trace[473227479] 'agreement among raft nodes before linearized reading' (duration: 355.804474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.673417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:19.317395Z","time spent":"356.013793ms","remote":"127.0.0.1:54800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":41,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-16T11:51:19.67344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.052764ms","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":"warn","ts":"2026-04-16T11:51:19.67345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:18.939009Z","time spent":"734.418878ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T11:51:19.673475Z","caller":"traceutil/trace.go:171","msg":"trace[675150122] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5740; }","duration":"148.113035ms","start":"2026-04-16T11:51:19.525342Z","end":"2026-04-16T11:51:19.673455Z","steps":["trace[675150122] 'agreement among raft nodes before linearized reading' (duration: 148.075154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.673673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.598299ms","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-04-16T11:51:19.673706Z","caller":"traceutil/trace.go:171","msg":"trace[239264055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5740; }","duration":"731.680793ms","start":"2026-04-16T11:51:18.942015Z","end":"2026-04-16T11:51:19.673696Z","steps":["trace[239264055] 'agreement among raft nodes before linearized reading' (duration: 731.574037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.673741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:18.941997Z","time spent":"731.736836ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T11:51:19.673749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.889252ms","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-04-16T11:51:19.673785Z","caller":"traceutil/trace.go:171","msg":"trace[1277307305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5740; }","duration":"731.962296ms","start":"2026-04-16T11:51:18.941813Z","end":"2026-04-16T11:51:19.673775Z","steps":["trace[1277307305] 'agreement among raft nodes before linearized reading' (duration: 731.85596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.673813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:18.941802Z","time spent":"732.005407ms","remote":"127.0.0.1:54870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T11:51:19.674821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.357868ms","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":"info","ts":"2026-04-16T11:51:19.6749Z","caller":"traceutil/trace.go:171","msg":"trace[1313297278] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:5740; }","duration":"345.460552ms","start":"2026-04-16T11:51:19.329421Z","end":"2026-04-16T11:51:19.674881Z","steps":["trace[1313297278] 'agreement among raft nodes before linearized reading' (duration: 343.652579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.674947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:19.32933Z","time spent":"345.608298ms","remote":"127.0.0.1:53164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-04-16T11:51:19.673408Z","caller":"traceutil/trace.go:171","msg":"trace[452373102] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:5740; }","duration":"590.240454ms","start":"2026-04-16T11:51:19.083025Z","end":"2026-04-16T11:51:19.673265Z","steps":["trace[452373102] 'agreement among raft nodes before linearized reading' (duration: 590.081907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T11:51:19.67576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T11:51:19.082998Z","time spent":"592.740498ms","remote":"127.0.0.1:54788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":6,"response size":31,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-04-16T11:51:22.880076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2992} {"level":"info","ts":"2026-04-16T11:51:23.003024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2992,"took":"120.385623ms","hash":591360806,"current-db-size-bytes":55541760,"current-db-size":"56 MB","current-db-size-in-use-bytes":30957568,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-16T11:51:23.0031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":591360806,"revision":2992,"compact-revision":-1} {"level":"info","ts":"2026-04-16T11:53:10.655317Z","caller":"traceutil/trace.go:171","msg":"trace[1772034847] transaction","detail":"{read_only:false; response_revision:6578; number_of_response:1; }","duration":"214.528694ms","start":"2026-04-16T11:53:10.440762Z","end":"2026-04-16T11:53:10.65529Z","steps":["trace[1772034847] 'process raft request' (duration: 214.490223ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:53:10.656182Z","caller":"traceutil/trace.go:171","msg":"trace[1982473574] transaction","detail":"{read_only:false; response_revision:6575; number_of_response:1; }","duration":"217.884308ms","start":"2026-04-16T11:53:10.438284Z","end":"2026-04-16T11:53:10.656168Z","steps":["trace[1982473574] 'process raft request' (duration: 213.768179ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:53:10.656599Z","caller":"traceutil/trace.go:171","msg":"trace[681604749] transaction","detail":"{read_only:false; response_revision:6577; number_of_response:1; }","duration":"218.174722ms","start":"2026-04-16T11:53:10.438406Z","end":"2026-04-16T11:53:10.65658Z","steps":["trace[681604749] 'process raft request' (duration: 216.811747ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T11:53:10.656834Z","caller":"traceutil/trace.go:171","msg":"trace[1262588009] transaction","detail":"{read_only:false; response_revision:6576; number_of_response:1; }","duration":"218.344829ms","start":"2026-04-16T11:53:10.438301Z","end":"2026-04-16T11:53:10.656646Z","steps":["trace[1262588009] 'process raft request' (duration: 216.864478ms)"],"step_count":1}