{"level":"warn","ts":"2026-06-10T08:23:29.6982Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:23:29.698295Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.35:2380","--initial-cluster=instance=https://199.19.213.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-10T08:23:29.69837Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:23:29.698385Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-06-10T08:23:29.698408Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T08:23:29.699146Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"]} {"level":"info","ts":"2026-06-10T08:23:29.699239Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-10T08:23:29.705673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.152545ms"} {"level":"info","ts":"2026-06-10T08:23:29.714088Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1606b245347dc08","cluster-id":"e68e205b85faf98a"} {"level":"info","ts":"2026-06-10T08:23:29.714165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T08:23:29.71419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 0"} {"level":"info","ts":"2026-06-10T08:23:29.714204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1606b245347dc08 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T08:23:29.714219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 1"} {"level":"info","ts":"2026-06-10T08:23:29.714257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"warn","ts":"2026-06-10T08:23:29.725086Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T08:23:29.726262Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T08:23:29.727528Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-10T08:23:29.729419Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1606b245347dc08","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T08:23:29.730157Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1606b245347dc08","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T08:23:29.730207Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T08:23:29.73029Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T08:23:29.7303Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-10T08:23:29.732808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"info","ts":"2026-06-10T08:23:29.732945Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","added-peer-id":"d1606b245347dc08","added-peer-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-06-10T08:23:29.733855Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-10T08:23:29.734064Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1606b245347dc08","initial-advertise-peer-urls":["https://199.19.213.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T08:23:29.734104Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T08:23:29.734142Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-06-10T08:23:29.73416Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-06-10T08:23:29.815179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T08:23:29.81522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T08:23:29.815236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgPreVoteResp from d1606b245347dc08 at term 1"} {"level":"info","ts":"2026-06-10T08:23:29.815249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became candidate at term 2"} {"level":"info","ts":"2026-06-10T08:23:29.815256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgVoteResp from d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-06-10T08:23:29.815268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became leader at term 2"} {"level":"info","ts":"2026-06-10T08:23:29.815285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1606b245347dc08 elected leader d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-06-10T08:23:29.816212Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:23:29.816964Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1606b245347dc08","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.35:2379]}","request-path":"/0/members/d1606b245347dc08/attributes","cluster-id":"e68e205b85faf98a","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T08:23:29.817017Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:23:29.817051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:23:29.817506Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:23:29.817557Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T08:23:29.817621Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T08:23:29.817703Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:23:29.817808Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:23:29.819227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.35:2379"} {"level":"info","ts":"2026-06-10T08:23:29.820672Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-10T08:23:34.102884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.260102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:23:34.103025Z","caller":"traceutil/trace.go:171","msg":"trace[1817194207] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:240; }","duration":"605.60732ms","start":"2026-06-10T08:23:33.497387Z","end":"2026-06-10T08:23:34.102994Z","steps":["trace[1817194207] 'read index received' (duration: 139.71467ms)","trace[1817194207] 'applied index is now lower than readState.Index' (duration: 465.89153ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:23:34.10307Z","caller":"traceutil/trace.go:171","msg":"trace[1687835767] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"874.813885ms","start":"2026-06-10T08:23:33.228234Z","end":"2026-06-10T08:23:34.103047Z","steps":["trace[1687835767] 'process raft request' (duration: 408.946027ms)","trace[1687835767] 'compare' (duration: 465.132207ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:23:34.103185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:33.228219Z","time spent":"874.911369ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:23:34.103243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.057177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:23:34.103296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.778455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:23:34.103368Z","caller":"traceutil/trace.go:171","msg":"trace[11184180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:235; }","duration":"606.022532ms","start":"2026-06-10T08:23:33.497337Z","end":"2026-06-10T08:23:34.10336Z","steps":["trace[11184180] 'agreement among raft nodes before linearized reading' (duration: 605.751444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:23:34.103396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:33.497317Z","time spent":"606.071354ms","remote":"127.0.0.1:42770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T08:23:34.103314Z","caller":"traceutil/trace.go:171","msg":"trace[1159135532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:235; }","duration":"260.159939ms","start":"2026-06-10T08:23:33.843137Z","end":"2026-06-10T08:23:34.103297Z","steps":["trace[1159135532] 'agreement among raft nodes before linearized reading' (duration: 260.045616ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:23:34.103231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.102525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:23:34.103645Z","caller":"traceutil/trace.go:171","msg":"trace[97255926] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:235; }","duration":"230.556749ms","start":"2026-06-10T08:23:33.87308Z","end":"2026-06-10T08:23:34.103637Z","steps":["trace[97255926] 'agreement among raft nodes before linearized reading' (duration: 230.125707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:23:34.60865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096969612407844,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:23:34.900919Z","caller":"traceutil/trace.go:171","msg":"trace[1128276113] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"795.212753ms","start":"2026-06-10T08:23:34.105674Z","end":"2026-06-10T08:23:34.900887Z","steps":["trace[1128276113] 'process raft request' (duration: 757.457751ms)","trace[1128276113] 'compare' (duration: 37.616148ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:23:34.901065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:34.105655Z","time spent":"795.344636ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:23:35.150878Z","caller":"traceutil/trace.go:171","msg":"trace[1473318355] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:241; }","duration":"1.043220981s","start":"2026-06-10T08:23:34.107629Z","end":"2026-06-10T08:23:35.15085Z","steps":["trace[1473318355] 'read index received' (duration: 755.591035ms)","trace[1473318355] 'applied index is now lower than readState.Index' (duration: 287.628975ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:23:35.150932Z","caller":"traceutil/trace.go:171","msg":"trace[617078755] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"1.043294273s","start":"2026-06-10T08:23:34.107611Z","end":"2026-06-10T08:23:35.150906Z","steps":["trace[617078755] 'process raft request' (duration: 1.043098688s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:23:35.151059Z","caller":"traceutil/trace.go:171","msg":"trace[414454021] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"697.412026ms","start":"2026-06-10T08:23:34.453631Z","end":"2026-06-10T08:23:35.151044Z","steps":["trace[414454021] 'process raft request' (duration: 697.163939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:23:35.151077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:34.10759Z","time spent":"1.043403685s","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:23:35.151167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:34.453602Z","time spent":"697.497108ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:23:35.151205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.829133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:23:35.151268Z","caller":"traceutil/trace.go:171","msg":"trace[560582495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:239; }","duration":"656.977597ms","start":"2026-06-10T08:23:34.494274Z","end":"2026-06-10T08:23:35.151252Z","steps":["trace[560582495] 'agreement among raft nodes before linearized reading' (duration: 656.840452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:23:35.151303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:34.494252Z","time spent":"657.044369ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:23:35.151372Z","caller":"traceutil/trace.go:171","msg":"trace[2142767280] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"1.043664334s","start":"2026-06-10T08:23:34.107611Z","end":"2026-06-10T08:23:35.151276Z","steps":["trace[2142767280] 'process raft request' (duration: 1.034816458s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:23:35.151204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043561441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:23:35.152155Z","caller":"traceutil/trace.go:171","msg":"trace[1221856745] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:0; response_revision:239; }","duration":"1.044536709s","start":"2026-06-10T08:23:34.107588Z","end":"2026-06-10T08:23:35.152125Z","steps":["trace[1221856745] 'agreement among raft nodes before linearized reading' (duration: 1.043564961s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:23:35.152247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:34.107553Z","time spent":"1.044664372s","remote":"127.0.0.1:42964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":29,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-06-10T08:23:35.152084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:23:34.10759Z","time spent":"1.044420465s","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:24:09.839052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096969612408485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:24:09.897308Z","caller":"traceutil/trace.go:171","msg":"trace[1177017386] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"586.138336ms","start":"2026-06-10T08:24:09.311143Z","end":"2026-06-10T08:24:09.897281Z","steps":["trace[1177017386] 'process raft request' (duration: 585.989613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.898011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:09.311125Z","time spent":"586.537815ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:24:09.903439Z","caller":"traceutil/trace.go:171","msg":"trace[1382543119] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"586.900034ms","start":"2026-06-10T08:24:09.316501Z","end":"2026-06-10T08:24:09.903401Z","steps":["trace[1382543119] 'process raft request' (duration: 586.658398ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:24:09.903501Z","caller":"traceutil/trace.go:171","msg":"trace[1318195964] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:578; }","duration":"564.987434ms","start":"2026-06-10T08:24:09.338499Z","end":"2026-06-10T08:24:09.903486Z","steps":["trace[1318195964] 'read index received' (duration: 558.918022ms)","trace[1318195964] 'applied index is now lower than readState.Index' (duration: 6.068862ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:24:09.903586Z","caller":"traceutil/trace.go:171","msg":"trace[2082862117] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"541.414454ms","start":"2026-06-10T08:24:09.362163Z","end":"2026-06-10T08:24:09.903577Z","steps":["trace[2082862117] 'process raft request' (duration: 541.26488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.903582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:09.316483Z","time spent":"587.023396ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:24:09.903646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:09.362137Z","time spent":"541.474945ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:24:09.903799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.622807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:24:09.903842Z","caller":"traceutil/trace.go:171","msg":"trace[1528335421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"562.69423ms","start":"2026-06-10T08:24:09.341139Z","end":"2026-06-10T08:24:09.903834Z","steps":["trace[1528335421] 'agreement among raft nodes before linearized reading' (duration: 562.588517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.903866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:09.341127Z","time spent":"562.734051ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:24:09.903909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.135069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:09.903947Z","caller":"traceutil/trace.go:171","msg":"trace[1447712891] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"229.190391ms","start":"2026-06-10T08:24:09.674751Z","end":"2026-06-10T08:24:09.903941Z","steps":["trace[1447712891] 'agreement among raft nodes before linearized reading' (duration: 229.138389ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.903991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.059205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:09.904019Z","caller":"traceutil/trace.go:171","msg":"trace[121074081] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:560; }","duration":"250.103857ms","start":"2026-06-10T08:24:09.653907Z","end":"2026-06-10T08:24:09.904011Z","steps":["trace[121074081] 'agreement among raft nodes before linearized reading' (duration: 250.066416ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.904043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.546707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:24:09.904061Z","caller":"traceutil/trace.go:171","msg":"trace[786181383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"565.606538ms","start":"2026-06-10T08:24:09.33845Z","end":"2026-06-10T08:24:09.904057Z","steps":["trace[786181383] 'agreement among raft nodes before linearized reading' (duration: 565.574567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.904075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:09.338427Z","time spent":"565.644638ms","remote":"127.0.0.1:43072","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-06-10T08:24:09.904116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.522547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:24:09.904138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.301489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:09.904139Z","caller":"traceutil/trace.go:171","msg":"trace[1370926633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:560; }","duration":"250.560768ms","start":"2026-06-10T08:24:09.653573Z","end":"2026-06-10T08:24:09.904134Z","steps":["trace[1370926633] 'agreement among raft nodes before linearized reading' (duration: 250.525237ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:24:09.904152Z","caller":"traceutil/trace.go:171","msg":"trace[487599182] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"228.35113ms","start":"2026-06-10T08:24:09.675796Z","end":"2026-06-10T08:24:09.904147Z","steps":["trace[487599182] 'agreement among raft nodes before linearized reading' (duration: 228.3292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.90421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.275351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:24:09.904215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.658179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:09.904227Z","caller":"traceutil/trace.go:171","msg":"trace[205759028] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:560; }","duration":"229.293062ms","start":"2026-06-10T08:24:09.67493Z","end":"2026-06-10T08:24:09.904223Z","steps":["trace[205759028] 'agreement among raft nodes before linearized reading' (duration: 229.269801ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:24:09.904232Z","caller":"traceutil/trace.go:171","msg":"trace[1033789329] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:560; }","duration":"250.717851ms","start":"2026-06-10T08:24:09.653509Z","end":"2026-06-10T08:24:09.904227Z","steps":["trace[1033789329] 'agreement among raft nodes before linearized reading' (duration: 250.69138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.90431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.187228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:24:09.904333Z","caller":"traceutil/trace.go:171","msg":"trace[1792852160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"389.327161ms","start":"2026-06-10T08:24:09.515Z","end":"2026-06-10T08:24:09.904327Z","steps":["trace[1792852160] 'agreement among raft nodes before linearized reading' (duration: 389.28957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.904349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:09.514981Z","time spent":"389.364631ms","remote":"127.0.0.1:43072","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-06-10T08:24:09.90367Z","caller":"traceutil/trace.go:171","msg":"trace[1609348274] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"581.727183ms","start":"2026-06-10T08:24:09.321835Z","end":"2026-06-10T08:24:09.903562Z","steps":["trace[1609348274] 'process raft request' (duration: 581.456317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:09.904592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:09.321815Z","time spent":"582.751568ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:24:10.102987Z","caller":"traceutil/trace.go:171","msg":"trace[633753718] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"116.685657ms","start":"2026-06-10T08:24:09.986285Z","end":"2026-06-10T08:24:10.102971Z","steps":["trace[633753718] 'process raft request' (duration: 74.872728ms)","trace[633753718] 'compare' (duration: 41.719207ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:24:10.116418Z","caller":"traceutil/trace.go:171","msg":"trace[1608571617] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"121.166199ms","start":"2026-06-10T08:24:09.99523Z","end":"2026-06-10T08:24:10.116396Z","steps":["trace[1608571617] 'process raft request' (duration: 120.944884ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:24:10.116568Z","caller":"traceutil/trace.go:171","msg":"trace[953089418] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:591; }","duration":"120.970184ms","start":"2026-06-10T08:24:09.995575Z","end":"2026-06-10T08:24:10.116545Z","steps":["trace[953089418] 'read index received' (duration: 65.632152ms)","trace[953089418] 'applied index is now lower than readState.Index' (duration: 55.337002ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:24:10.116744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.683819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:10.116773Z","caller":"traceutil/trace.go:171","msg":"trace[290290641] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:573; }","duration":"120.7364ms","start":"2026-06-10T08:24:09.996028Z","end":"2026-06-10T08:24:10.116765Z","steps":["trace[290290641] 'agreement among raft nodes before linearized reading' (duration: 120.641318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:10.116891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.311732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:10.116909Z","caller":"traceutil/trace.go:171","msg":"trace[1338736710] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:573; }","duration":"121.347913ms","start":"2026-06-10T08:24:09.995556Z","end":"2026-06-10T08:24:10.116904Z","steps":["trace[1338736710] 'agreement among raft nodes before linearized reading' (duration: 121.316462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:10.117113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.522301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:10.117132Z","caller":"traceutil/trace.go:171","msg":"trace[784479974] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:573; }","duration":"119.550581ms","start":"2026-06-10T08:24:09.997576Z","end":"2026-06-10T08:24:10.117126Z","steps":["trace[784479974] 'agreement among raft nodes before linearized reading' (duration: 119.522861ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:24:10.803419Z","caller":"traceutil/trace.go:171","msg":"trace[549658670] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"630.830839ms","start":"2026-06-10T08:24:10.172568Z","end":"2026-06-10T08:24:10.803399Z","steps":["trace[549658670] 'process raft request' (duration: 537.541807ms)","trace[549658670] 'compare' (duration: 93.162579ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:24:10.803526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:10.172527Z","time spent":"630.952222ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:24:10.807626Z","caller":"traceutil/trace.go:171","msg":"trace[121146089] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"626.012977ms","start":"2026-06-10T08:24:10.181596Z","end":"2026-06-10T08:24:10.807609Z","steps":["trace[121146089] 'process raft request' (duration: 625.622758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:10.807799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:10.18157Z","time spent":"626.15565ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:24:10.80797Z","caller":"traceutil/trace.go:171","msg":"trace[1181126261] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"609.956534ms","start":"2026-06-10T08:24:10.198005Z","end":"2026-06-10T08:24:10.807962Z","steps":["trace[1181126261] 'process raft request' (duration: 609.3319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:10.808113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:10.197983Z","time spent":"610.101917ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:24:16.519738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.644675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:24:16.519775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.1175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:24:16.519849Z","caller":"traceutil/trace.go:171","msg":"trace[840400436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"264.8364ms","start":"2026-06-10T08:24:16.254997Z","end":"2026-06-10T08:24:16.519834Z","steps":["trace[840400436] 'range keys from in-memory index tree' (duration: 264.523512ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:24:16.519889Z","caller":"traceutil/trace.go:171","msg":"trace[8221334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"573.255893ms","start":"2026-06-10T08:24:15.946605Z","end":"2026-06-10T08:24:16.519861Z","steps":["trace[8221334] 'range keys from in-memory index tree' (duration: 572.957566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:16.519855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.623837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-10T08:24:16.519941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:15.946599Z","time spent":"573.331845ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:24:16.519966Z","caller":"traceutil/trace.go:171","msg":"trace[1574200061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"579.805411ms","start":"2026-06-10T08:24:15.940144Z","end":"2026-06-10T08:24:16.519949Z","steps":["trace[1574200061] 'range keys from in-memory index tree' (duration: 579.437022ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:16.520031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:15.940126Z","time spent":"579.869972ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:24:16.520404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.003814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T08:24:16.520534Z","caller":"traceutil/trace.go:171","msg":"trace[1628344990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"351.209729ms","start":"2026-06-10T08:24:16.169301Z","end":"2026-06-10T08:24:16.520511Z","steps":["trace[1628344990] 'range keys from in-memory index tree' (duration: 350.509404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:16.520619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:16.169286Z","time spent":"351.319482ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T08:24:16.520657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.068811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:24:16.520738Z","caller":"traceutil/trace.go:171","msg":"trace[550869068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"574.181294ms","start":"2026-06-10T08:24:15.946537Z","end":"2026-06-10T08:24:16.520718Z","steps":["trace[550869068] 'range keys from in-memory index tree' (duration: 573.044988ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:16.520783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:15.946527Z","time spent":"574.247615ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:24:16.548973Z","caller":"traceutil/trace.go:171","msg":"trace[2138911659] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"269.36485ms","start":"2026-06-10T08:24:16.27959Z","end":"2026-06-10T08:24:16.548954Z","steps":["trace[2138911659] 'process raft request' (duration: 268.726946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:17.674745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.79244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:24:17.6749Z","caller":"traceutil/trace.go:171","msg":"trace[368186837] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.072558687s","start":"2026-06-10T08:24:16.602309Z","end":"2026-06-10T08:24:17.674868Z","steps":["trace[368186837] 'process raft request' (duration: 555.589392ms)","trace[368186837] 'compare' (duration: 516.327081ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:24:17.675046Z","caller":"traceutil/trace.go:171","msg":"trace[2068703985] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"124.815921ms","start":"2026-06-10T08:24:17.550212Z","end":"2026-06-10T08:24:17.675028Z","steps":["trace[2068703985] 'process raft request' (duration: 124.669867ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:17.675063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:16.602275Z","time spent":"1.072689181s","remote":"127.0.0.1:43270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:24:17.675079Z","caller":"traceutil/trace.go:171","msg":"trace[278453412] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"904.449151ms","start":"2026-06-10T08:24:16.770594Z","end":"2026-06-10T08:24:17.675043Z","steps":["trace[278453412] 'read index received' (duration: 387.239411ms)","trace[278453412] 'applied index is now lower than readState.Index' (duration: 517.20726ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:24:17.67535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.741047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkffj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-10T08:24:17.675401Z","caller":"traceutil/trace.go:171","msg":"trace[1372651341] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkffj; range_end:; response_count:1; response_revision:639; }","duration":"904.837759ms","start":"2026-06-10T08:24:16.770554Z","end":"2026-06-10T08:24:17.675391Z","steps":["trace[1372651341] 'agreement among raft nodes before linearized reading' (duration: 904.658975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:17.67535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.582348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:24:17.674606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.417431ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:17.675496Z","caller":"traceutil/trace.go:171","msg":"trace[373874309] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"727.319981ms","start":"2026-06-10T08:24:16.948164Z","end":"2026-06-10T08:24:17.675484Z","steps":["trace[373874309] 'range keys from in-memory index tree' (duration: 726.404691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:17.675435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:16.770535Z","time spent":"904.89194ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-wkffj\" "} {"level":"info","ts":"2026-06-10T08:24:17.675608Z","caller":"traceutil/trace.go:171","msg":"trace[36272254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"419.770782ms","start":"2026-06-10T08:24:17.255702Z","end":"2026-06-10T08:24:17.675473Z","steps":["trace[36272254] 'agreement among raft nodes before linearized reading' (duration: 419.593648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:17.675702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:17.25565Z","time spent":"420.036118ms","remote":"127.0.0.1:42770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T08:24:21.150695Z","caller":"traceutil/trace.go:171","msg":"trace[1259862493] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"189.015377ms","start":"2026-06-10T08:24:20.96166Z","end":"2026-06-10T08:24:21.150676Z","steps":["trace[1259862493] 'process raft request' (duration: 188.923505ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:24:33.1567Z","caller":"traceutil/trace.go:171","msg":"trace[245794788] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:828; }","duration":"295.305967ms","start":"2026-06-10T08:24:32.861362Z","end":"2026-06-10T08:24:33.156668Z","steps":["trace[245794788] 'read index received' (duration: 295.290106ms)","trace[245794788] 'applied index is now lower than readState.Index' (duration: 13.921µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:24:33.156876Z","caller":"traceutil/trace.go:171","msg":"trace[1003651672] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"336.504688ms","start":"2026-06-10T08:24:32.820247Z","end":"2026-06-10T08:24:33.156752Z","steps":["trace[1003651672] 'process raft request' (duration: 336.224322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:33.156937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.558353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qhrnd\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-10T08:24:33.156978Z","caller":"traceutil/trace.go:171","msg":"trace[981022259] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qhrnd; range_end:; response_count:1; response_revision:801; }","duration":"295.654724ms","start":"2026-06-10T08:24:32.861312Z","end":"2026-06-10T08:24:33.156967Z","steps":["trace[981022259] 'agreement among raft nodes before linearized reading' (duration: 295.480161ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:33.157135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:32.82023Z","time spent":"336.751482ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:24:33.530274Z","caller":"traceutil/trace.go:171","msg":"trace[1488406469] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"615.388934ms","start":"2026-06-10T08:24:32.914861Z","end":"2026-06-10T08:24:33.53025Z","steps":["trace[1488406469] 'process raft request' (duration: 605.289034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:33.530532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:32.914834Z","time spent":"615.598387ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:24:33.539328Z","caller":"traceutil/trace.go:171","msg":"trace[147261842] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"376.042613ms","start":"2026-06-10T08:24:33.163267Z","end":"2026-06-10T08:24:33.53931Z","steps":["trace[147261842] 'process raft request' (duration: 375.838029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:33.539469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:33.163248Z","time spent":"376.149515ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:24:33.539528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.256226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-10T08:24:33.539587Z","caller":"traceutil/trace.go:171","msg":"trace[2072398005] range","detail":"{range_begin:/registry/masterleases/199.19.213.35; range_end:; response_count:1; response_revision:803; }","duration":"361.368208ms","start":"2026-06-10T08:24:33.178203Z","end":"2026-06-10T08:24:33.539571Z","steps":["trace[2072398005] 'agreement among raft nodes before linearized reading' (duration: 361.178875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:33.539625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:24:33.178188Z","time spent":"361.43033ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.35\" "} {"level":"info","ts":"2026-06-10T08:24:33.539229Z","caller":"traceutil/trace.go:171","msg":"trace[1168081128] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"360.96337ms","start":"2026-06-10T08:24:33.178246Z","end":"2026-06-10T08:24:33.539209Z","steps":["trace[1168081128] 'read index received' (duration: 341.929265ms)","trace[1168081128] 'applied index is now lower than readState.Index' (duration: 19.033415ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:24:33.540011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.223705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:33.540071Z","caller":"traceutil/trace.go:171","msg":"trace[2052141849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"285.329428ms","start":"2026-06-10T08:24:33.254729Z","end":"2026-06-10T08:24:33.540059Z","steps":["trace[2052141849] 'agreement among raft nodes before linearized reading' (duration: 285.250806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:24:33.540073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.177817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:24:33.54011Z","caller":"traceutil/trace.go:171","msg":"trace[476147482] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:803; }","duration":"236.275418ms","start":"2026-06-10T08:24:33.303824Z","end":"2026-06-10T08:24:33.5401Z","steps":["trace[476147482] 'agreement among raft nodes before linearized reading' (duration: 236.214627ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:13.323207Z","caller":"traceutil/trace.go:171","msg":"trace[816054212] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"138.558173ms","start":"2026-06-10T08:25:13.18463Z","end":"2026-06-10T08:25:13.323189Z","steps":["trace[816054212] 'process raft request' (duration: 96.718971ms)","trace[816054212] 'compare' (duration: 41.742611ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:25:18.896126Z","caller":"traceutil/trace.go:171","msg":"trace[2101281136] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"187.427782ms","start":"2026-06-10T08:25:18.708673Z","end":"2026-06-10T08:25:18.896101Z","steps":["trace[2101281136] 'process raft request' (duration: 186.21943ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:49.524878Z","caller":"traceutil/trace.go:171","msg":"trace[384723347] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"442.407937ms","start":"2026-06-10T08:25:49.082449Z","end":"2026-06-10T08:25:49.524857Z","steps":["trace[384723347] 'read index received' (duration: 442.330206ms)","trace[384723347] 'applied index is now lower than readState.Index' (duration: 76.901µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:25:49.525058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.59665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-10T08:25:49.525087Z","caller":"traceutil/trace.go:171","msg":"trace[1001674177] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"442.675501ms","start":"2026-06-10T08:25:49.082404Z","end":"2026-06-10T08:25:49.52508Z","steps":["trace[1001674177] 'agreement among raft nodes before linearized reading' (duration: 442.56097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:49.525112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.082383Z","time spent":"442.723802ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:25:49.525226Z","caller":"traceutil/trace.go:171","msg":"trace[398457890] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"445.381258ms","start":"2026-06-10T08:25:49.079812Z","end":"2026-06-10T08:25:49.525193Z","steps":["trace[398457890] 'process raft request' (duration: 444.90897ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:49.525493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.079779Z","time spent":"445.611161ms","remote":"127.0.0.1:43072","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-06-10T08:25:50.02542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096969612411049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:25:50.172624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.198506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:25:50.172775Z","caller":"traceutil/trace.go:171","msg":"trace[2004606966] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"736.880321ms","start":"2026-06-10T08:25:49.435865Z","end":"2026-06-10T08:25:50.172745Z","steps":["trace[2004606966] 'process raft request' (duration: 623.439792ms)","trace[2004606966] 'compare' (duration: 112.85897ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:25:50.173058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.43583Z","time spent":"736.997295ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:25:50.24303Z","caller":"traceutil/trace.go:171","msg":"trace[639171412] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"713.396232ms","start":"2026-06-10T08:25:49.52958Z","end":"2026-06-10T08:25:50.242976Z","steps":["trace[639171412] 'process raft request' (duration: 713.26201ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:50.243208Z","caller":"traceutil/trace.go:171","msg":"trace[1815026661] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1922; }","duration":"718.240345ms","start":"2026-06-10T08:25:49.524942Z","end":"2026-06-10T08:25:50.243183Z","steps":["trace[1815026661] 'read index received' (duration: 534.374603ms)","trace[1815026661] 'applied index is now lower than readState.Index' (duration: 183.864051ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:25:50.243171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.529553Z","time spent":"713.541144ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:25:50.243356Z","caller":"traceutil/trace.go:171","msg":"trace[1361193229] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"368.934796ms","start":"2026-06-10T08:25:49.874393Z","end":"2026-06-10T08:25:50.243328Z","steps":["trace[1361193229] 'process raft request' (duration: 368.54905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.243427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138598627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-10T08:25:50.243428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.091911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-10T08:25:50.243489Z","caller":"traceutil/trace.go:171","msg":"trace[824311288] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8; range_end:; response_count:1; response_revision:1855; }","duration":"895.191613ms","start":"2026-06-10T08:25:49.348281Z","end":"2026-06-10T08:25:50.243472Z","steps":["trace[824311288] 'agreement among raft nodes before linearized reading' (duration: 894.970889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.243504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143305478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-10T08:25:50.243499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.874369Z","time spent":"369.052417ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:25:50.243531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.348257Z","time spent":"895.264945ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" "} {"level":"warn","ts":"2026-06-10T08:25:50.243562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.837226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:25:50.243578Z","caller":"traceutil/trace.go:171","msg":"trace[929840795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1855; }","duration":"986.852837ms","start":"2026-06-10T08:25:49.25672Z","end":"2026-06-10T08:25:50.243573Z","steps":["trace[929840795] 'agreement among raft nodes before linearized reading' (duration: 986.826356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.243603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.256655Z","time spent":"986.935638ms","remote":"127.0.0.1:42770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-10T08:25:50.243524Z","caller":"traceutil/trace.go:171","msg":"trace[87819565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1855; }","duration":"1.143346849s","start":"2026-06-10T08:25:49.100172Z","end":"2026-06-10T08:25:50.243519Z","steps":["trace[87819565] 'agreement among raft nodes before linearized reading' (duration: 1.143290517s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.243637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.100161Z","time spent":"1.1434683s","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:25:50.243451Z","caller":"traceutil/trace.go:171","msg":"trace[1541411089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1855; }","duration":"1.138660069s","start":"2026-06-10T08:25:49.104783Z","end":"2026-06-10T08:25:50.243444Z","steps":["trace[1541411089] 'agreement among raft nodes before linearized reading' (duration: 1.138511136s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.243742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.104769Z","time spent":"1.138968024s","remote":"127.0.0.1:43072","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-06-10T08:25:50.243777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.264806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-10T08:25:50.24381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.716245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:25:50.243813Z","caller":"traceutil/trace.go:171","msg":"trace[1185926800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1855; }","duration":"731.336527ms","start":"2026-06-10T08:25:49.512466Z","end":"2026-06-10T08:25:50.243803Z","steps":["trace[1185926800] 'agreement among raft nodes before linearized reading' (duration: 731.246476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.243805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.870351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-06-10T08:25:50.243848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016675441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-06-10T08:25:50.243893Z","caller":"traceutil/trace.go:171","msg":"trace[216993784] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1855; }","duration":"664.996522ms","start":"2026-06-10T08:25:49.578875Z","end":"2026-06-10T08:25:50.243872Z","steps":["trace[216993784] 'agreement among raft nodes before linearized reading' (duration: 664.632627ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:50.243927Z","caller":"traceutil/trace.go:171","msg":"trace[1493109831] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8; range_end:; response_count:1; response_revision:1855; }","duration":"1.016806734s","start":"2026-06-10T08:25:49.227101Z","end":"2026-06-10T08:25:50.243908Z","steps":["trace[1493109831] 'agreement among raft nodes before linearized reading' (duration: 1.016451338s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:50.24383Z","caller":"traceutil/trace.go:171","msg":"trace[628744] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1855; }","duration":"825.771957ms","start":"2026-06-10T08:25:49.418052Z","end":"2026-06-10T08:25:50.243824Z","steps":["trace[628744] 'agreement among raft nodes before linearized reading' (duration: 825.734737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.243946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.578785Z","time spent":"665.152204ms","remote":"127.0.0.1:49938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-10T08:25:50.243986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.418038Z","time spent":"825.93199ms","remote":"127.0.0.1:45630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:25:50.243991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.227076Z","time spent":"1.016892155s","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" "} {"level":"warn","ts":"2026-06-10T08:25:50.24384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.512446Z","time spent":"731.388168ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:25:50.244143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139112946s","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-06-10T08:25:50.24423Z","caller":"traceutil/trace.go:171","msg":"trace[1696776355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1855; }","duration":"1.139256009s","start":"2026-06-10T08:25:49.104956Z","end":"2026-06-10T08:25:50.244212Z","steps":["trace[1696776355] 'agreement among raft nodes before linearized reading' (duration: 1.138334753s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.244413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:49.104932Z","time spent":"1.139466642s","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:25:50.396516Z","caller":"traceutil/trace.go:171","msg":"trace[657408012] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"146.63902ms","start":"2026-06-10T08:25:50.249789Z","end":"2026-06-10T08:25:50.396428Z","steps":["trace[657408012] 'process raft request' (duration: 146.415427ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:50.396516Z","caller":"traceutil/trace.go:171","msg":"trace[1898817713] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"146.086272ms","start":"2026-06-10T08:25:50.250343Z","end":"2026-06-10T08:25:50.39643Z","steps":["trace[1898817713] 'process raft request' (duration: 145.989691ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:50.39681Z","caller":"traceutil/trace.go:171","msg":"trace[682267157] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"145.880508ms","start":"2026-06-10T08:25:50.250898Z","end":"2026-06-10T08:25:50.396779Z","steps":["trace[682267157] 'process raft request' (duration: 145.484632ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:50.397033Z","caller":"traceutil/trace.go:171","msg":"trace[1663892887] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"145.92589ms","start":"2026-06-10T08:25:50.251099Z","end":"2026-06-10T08:25:50.397025Z","steps":["trace[1663892887] 'process raft request' (duration: 145.569373ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:50.729751Z","caller":"traceutil/trace.go:171","msg":"trace[1168961672] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"327.497922ms","start":"2026-06-10T08:25:50.402228Z","end":"2026-06-10T08:25:50.729726Z","steps":["trace[1168961672] 'process raft request' (duration: 327.358759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:50.730086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:50.402202Z","time spent":"327.627573ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:25:53.537364Z","caller":"traceutil/trace.go:171","msg":"trace[317102694] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"353.178198ms","start":"2026-06-10T08:25:53.184166Z","end":"2026-06-10T08:25:53.537345Z","steps":["trace[317102694] 'read index received' (duration: 352.980305ms)","trace[317102694] 'applied index is now lower than readState.Index' (duration: 197.183µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:25:53.537444Z","caller":"traceutil/trace.go:171","msg":"trace[817083047] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"487.257078ms","start":"2026-06-10T08:25:53.05015Z","end":"2026-06-10T08:25:53.537408Z","steps":["trace[817083047] 'process raft request' (duration: 487.076565ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:53.537587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:53.050123Z","time spent":"487.391721ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:25:53.537623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.426193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-10T08:25:53.537715Z","caller":"traceutil/trace.go:171","msg":"trace[531485136] range","detail":"{range_begin:/registry/masterleases/199.19.213.35; range_end:; response_count:1; response_revision:1882; }","duration":"353.573506ms","start":"2026-06-10T08:25:53.184123Z","end":"2026-06-10T08:25:53.537696Z","steps":["trace[531485136] 'agreement among raft nodes before linearized reading' (duration: 353.300112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:53.53779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:53.184102Z","time spent":"353.674087ms","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.35\" "} {"level":"info","ts":"2026-06-10T08:25:53.638483Z","caller":"traceutil/trace.go:171","msg":"trace[862545508] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"288.921697ms","start":"2026-06-10T08:25:53.349531Z","end":"2026-06-10T08:25:53.638453Z","steps":["trace[862545508] 'process raft request' (duration: 286.862652ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:25:53.638607Z","caller":"traceutil/trace.go:171","msg":"trace[232975175] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"101.163304ms","start":"2026-06-10T08:25:53.537414Z","end":"2026-06-10T08:25:53.638578Z","steps":["trace[232975175] 'read index received' (duration: 98.993888ms)","trace[232975175] 'applied index is now lower than readState.Index' (duration: 2.166916ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:25:53.638901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.387094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:25:53.638971Z","caller":"traceutil/trace.go:171","msg":"trace[1114141043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1883; }","duration":"384.483617ms","start":"2026-06-10T08:25:53.25447Z","end":"2026-06-10T08:25:53.638954Z","steps":["trace[1114141043] 'agreement among raft nodes before linearized reading' (duration: 384.198461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:53.63896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.805338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" ","response":"range_response_count:1 size:3932"} {"level":"warn","ts":"2026-06-10T08:25:53.639038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:53.254464Z","time spent":"384.562398ms","remote":"127.0.0.1:42770","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-06-10T08:25:53.639059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.574159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10327"} {"level":"info","ts":"2026-06-10T08:25:53.639118Z","caller":"traceutil/trace.go:171","msg":"trace[151835515] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1883; }","duration":"425.67341ms","start":"2026-06-10T08:25:53.213433Z","end":"2026-06-10T08:25:53.639106Z","steps":["trace[151835515] 'agreement among raft nodes before linearized reading' (duration: 425.240842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:53.639147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:53.213419Z","time spent":"425.719621ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":10351,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-10T08:25:53.639118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.539873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-06-10T08:25:53.639195Z","caller":"traceutil/trace.go:171","msg":"trace[1816073183] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1883; }","duration":"376.675235ms","start":"2026-06-10T08:25:53.262502Z","end":"2026-06-10T08:25:53.639177Z","steps":["trace[1816073183] 'agreement among raft nodes before linearized reading' (duration: 376.143106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:53.639262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:53.262484Z","time spent":"376.763296ms","remote":"127.0.0.1:49938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-10T08:25:53.639508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.390911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" ","response":"range_response_count:1 size:3932"} {"level":"info","ts":"2026-06-10T08:25:53.639585Z","caller":"traceutil/trace.go:171","msg":"trace[18560249] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8; range_end:; response_count:1; response_revision:1883; }","duration":"385.489182ms","start":"2026-06-10T08:25:53.254079Z","end":"2026-06-10T08:25:53.639569Z","steps":["trace[18560249] 'agreement among raft nodes before linearized reading' (duration: 384.613648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:53.639773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:53.254039Z","time spent":"385.720127ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3956,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" "} {"level":"info","ts":"2026-06-10T08:25:53.639067Z","caller":"traceutil/trace.go:171","msg":"trace[161397869] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8; range_end:; response_count:1; response_revision:1883; }","duration":"382.95667ms","start":"2026-06-10T08:25:53.256088Z","end":"2026-06-10T08:25:53.639045Z","steps":["trace[161397869] 'agreement among raft nodes before linearized reading' (duration: 382.521372ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:25:53.641864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:25:53.256072Z","time spent":"385.773697ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3956,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5c78ed4d-2ad7-467f-901e-e6e085e643e8\" "} {"level":"info","ts":"2026-06-10T08:25:56.047458Z","caller":"traceutil/trace.go:171","msg":"trace[282351638] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"104.738309ms","start":"2026-06-10T08:25:55.942693Z","end":"2026-06-10T08:25:56.047431Z","steps":["trace[282351638] 'process raft request' (duration: 104.499034ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:04.770207Z","caller":"traceutil/trace.go:171","msg":"trace[649250869] linearizableReadLoop","detail":"{readStateIndex:2046; appliedIndex:2045; }","duration":"393.389818ms","start":"2026-06-10T08:26:04.376787Z","end":"2026-06-10T08:26:04.770177Z","steps":["trace[649250869] 'read index received' (duration: 393.177455ms)","trace[649250869] 'applied index is now lower than readState.Index' (duration: 210.543µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:26:04.770504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.934329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:26:04.770545Z","caller":"traceutil/trace.go:171","msg":"trace[2106551186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1973; }","duration":"375.99299ms","start":"2026-06-10T08:26:04.394541Z","end":"2026-06-10T08:26:04.770534Z","steps":["trace[2106551186] 'agreement among raft nodes before linearized reading' (duration: 375.797997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:04.770545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.697582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-10T08:26:04.770584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:04.394523Z","time spent":"376.04878ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-10T08:26:04.77057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.539758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-10T08:26:04.770569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.465743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-hfbc5\" ","response":"range_response_count:1 size:5817"} {"level":"info","ts":"2026-06-10T08:26:04.770611Z","caller":"traceutil/trace.go:171","msg":"trace[439476287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1973; }","duration":"200.797573ms","start":"2026-06-10T08:26:04.569802Z","end":"2026-06-10T08:26:04.770599Z","steps":["trace[439476287] 'agreement among raft nodes before linearized reading' (duration: 200.58894ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:04.770637Z","caller":"traceutil/trace.go:171","msg":"trace[1222370743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1973; }","duration":"212.628259ms","start":"2026-06-10T08:26:04.557991Z","end":"2026-06-10T08:26:04.770619Z","steps":["trace[1222370743] 'agreement among raft nodes before linearized reading' (duration: 212.443126ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:04.770647Z","caller":"traceutil/trace.go:171","msg":"trace[1113290289] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-hfbc5; range_end:; response_count:1; response_revision:1973; }","duration":"219.582474ms","start":"2026-06-10T08:26:04.551046Z","end":"2026-06-10T08:26:04.770628Z","steps":["trace[1113290289] 'agreement among raft nodes before linearized reading' (duration: 219.405752ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:04.770621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.435272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T08:26:04.770732Z","caller":"traceutil/trace.go:171","msg":"trace[561871925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1973; }","duration":"210.584254ms","start":"2026-06-10T08:26:04.560122Z","end":"2026-06-10T08:26:04.770706Z","steps":["trace[561871925] 'agreement among raft nodes before linearized reading' (duration: 210.336531ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:04.770351Z","caller":"traceutil/trace.go:171","msg":"trace[2105786337] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"456.451406ms","start":"2026-06-10T08:26:04.313813Z","end":"2026-06-10T08:26:04.770265Z","steps":["trace[2105786337] 'process raft request' (duration: 456.130481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:04.771328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:04.313764Z","time spent":"457.490573ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:26:04.77133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.535748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:26:04.771386Z","caller":"traceutil/trace.go:171","msg":"trace[16370605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1973; }","duration":"394.62542ms","start":"2026-06-10T08:26:04.376748Z","end":"2026-06-10T08:26:04.771374Z","steps":["trace[16370605] 'agreement among raft nodes before linearized reading' (duration: 393.536141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:04.771421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:04.37673Z","time spent":"394.68103ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:26:25.391737Z","caller":"traceutil/trace.go:171","msg":"trace[1267456636] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"177.143225ms","start":"2026-06-10T08:26:25.214571Z","end":"2026-06-10T08:26:25.391715Z","steps":["trace[1267456636] 'process raft request' (duration: 176.983423ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:25.391967Z","caller":"traceutil/trace.go:171","msg":"trace[1205188380] linearizableReadLoop","detail":"{readStateIndex:2184; appliedIndex:2184; }","duration":"136.324122ms","start":"2026-06-10T08:26:25.255631Z","end":"2026-06-10T08:26:25.391955Z","steps":["trace[1205188380] 'read index received' (duration: 136.318642ms)","trace[1205188380] 'applied index is now lower than readState.Index' (duration: 4.18µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:26:25.392231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.496475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:26:25.392324Z","caller":"traceutil/trace.go:171","msg":"trace[655494323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"136.707308ms","start":"2026-06-10T08:26:25.255593Z","end":"2026-06-10T08:26:25.392301Z","steps":["trace[655494323] 'agreement among raft nodes before linearized reading' (duration: 136.455383ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:27.001093Z","caller":"traceutil/trace.go:171","msg":"trace[1770151341] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"155.087105ms","start":"2026-06-10T08:26:26.845988Z","end":"2026-06-10T08:26:27.001075Z","steps":["trace[1770151341] 'process raft request' (duration: 154.977224ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:27.01891Z","caller":"traceutil/trace.go:171","msg":"trace[1077546215] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2187; }","duration":"158.59563ms","start":"2026-06-10T08:26:26.860305Z","end":"2026-06-10T08:26:27.0189Z","steps":["trace[1077546215] 'read index received' (duration: 140.664981ms)","trace[1077546215] 'applied index is now lower than readState.Index' (duration: 17.929849ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:26:27.01902Z","caller":"traceutil/trace.go:171","msg":"trace[1498579917] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"171.948627ms","start":"2026-06-10T08:26:26.847043Z","end":"2026-06-10T08:26:27.018992Z","steps":["trace[1498579917] 'process raft request' (duration: 171.803724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.01921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.893585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:26:27.019276Z","caller":"traceutil/trace.go:171","msg":"trace[1446009815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2112; }","duration":"159.008598ms","start":"2026-06-10T08:26:26.860251Z","end":"2026-06-10T08:26:27.01926Z","steps":["trace[1446009815] 'agreement among raft nodes before linearized reading' (duration: 158.773973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.019332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.200082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:26:27.019411Z","caller":"traceutil/trace.go:171","msg":"trace[884436846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2112; }","duration":"127.324535ms","start":"2026-06-10T08:26:26.892068Z","end":"2026-06-10T08:26:27.019393Z","steps":["trace[884436846] 'agreement among raft nodes before linearized reading' (duration: 127.081901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.01947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.941277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-10T08:26:27.019472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.123251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-10T08:26:27.019534Z","caller":"traceutil/trace.go:171","msg":"trace[1780325700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2112; }","duration":"127.035719ms","start":"2026-06-10T08:26:26.892472Z","end":"2026-06-10T08:26:27.019508Z","steps":["trace[1780325700] 'agreement among raft nodes before linearized reading' (duration: 126.848396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.019565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.036539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:26:27.019593Z","caller":"traceutil/trace.go:171","msg":"trace[432769699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2112; }","duration":"127.075769ms","start":"2026-06-10T08:26:26.892511Z","end":"2026-06-10T08:26:27.019587Z","steps":["trace[432769699] 'agreement among raft nodes before linearized reading' (duration: 127.004928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.019625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.365985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T08:26:27.019649Z","caller":"traceutil/trace.go:171","msg":"trace[777573598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2112; }","duration":"127.414325ms","start":"2026-06-10T08:26:26.892229Z","end":"2026-06-10T08:26:27.019643Z","steps":["trace[777573598] 'agreement among raft nodes before linearized reading' (duration: 127.348104ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:27.019539Z","caller":"traceutil/trace.go:171","msg":"trace[1247121590] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2112; }","duration":"127.242383ms","start":"2026-06-10T08:26:26.892279Z","end":"2026-06-10T08:26:27.019521Z","steps":["trace[1247121590] 'agreement among raft nodes before linearized reading' (duration: 127.063851ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:27.445494Z","caller":"traceutil/trace.go:171","msg":"trace[1759912931] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"420.567792ms","start":"2026-06-10T08:26:27.024904Z","end":"2026-06-10T08:26:27.445471Z","steps":["trace[1759912931] 'process raft request' (duration: 420.517871ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:27.445572Z","caller":"traceutil/trace.go:171","msg":"trace[1772995896] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"421.325044ms","start":"2026-06-10T08:26:27.024219Z","end":"2026-06-10T08:26:27.445544Z","steps":["trace[1772995896] 'process raft request' (duration: 389.477708ms)","trace[1772995896] 'compare' (duration: 31.252077ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:26:27.445645Z","caller":"traceutil/trace.go:171","msg":"trace[992018241] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"421.148472ms","start":"2026-06-10T08:26:27.02449Z","end":"2026-06-10T08:26:27.445638Z","steps":["trace[992018241] 'process raft request' (duration: 420.742345ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.44568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:27.02448Z","time spent":"421.181052ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:26:27.445845Z","caller":"traceutil/trace.go:171","msg":"trace[2097985425] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"421.284804ms","start":"2026-06-10T08:26:27.024553Z","end":"2026-06-10T08:26:27.445838Z","steps":["trace[2097985425] 'process raft request' (duration: 420.836086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.445839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:27.024204Z","time spent":"421.447117ms","remote":"127.0.0.1:43072","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-06-10T08:26:27.445895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:27.024546Z","time spent":"421.326365ms","remote":"127.0.0.1:43072","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-06-10T08:26:27.445614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:27.024885Z","time spent":"420.669755ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:26:27.446071Z","caller":"traceutil/trace.go:171","msg":"trace[131523310] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2190; }","duration":"189.817045ms","start":"2026-06-10T08:26:27.25621Z","end":"2026-06-10T08:26:27.446027Z","steps":["trace[131523310] 'read index received' (duration: 157.39597ms)","trace[131523310] 'applied index is now lower than readState.Index' (duration: 32.417645ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:26:27.446795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.566816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:26:27.446849Z","caller":"traceutil/trace.go:171","msg":"trace[917355013] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"420.806326ms","start":"2026-06-10T08:26:27.025706Z","end":"2026-06-10T08:26:27.446513Z","steps":["trace[917355013] 'process raft request' (duration: 419.738129ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:27.446856Z","caller":"traceutil/trace.go:171","msg":"trace[430133104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2118; }","duration":"190.67418ms","start":"2026-06-10T08:26:27.256163Z","end":"2026-06-10T08:26:27.446837Z","steps":["trace[430133104] 'agreement among raft nodes before linearized reading' (duration: 190.014129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:26:27.446893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:26:27.025694Z","time spent":"421.177293ms","remote":"127.0.0.1:43072","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-06-10T08:26:51.826159Z","caller":"traceutil/trace.go:171","msg":"trace[1804353966] transaction","detail":"{read_only:false; response_revision:2242; number_of_response:1; }","duration":"176.02343ms","start":"2026-06-10T08:26:51.649811Z","end":"2026-06-10T08:26:51.825835Z","steps":["trace[1804353966] 'process raft request' (duration: 114.46975ms)","trace[1804353966] 'compare' (duration: 61.422378ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:26:51.852095Z","caller":"traceutil/trace.go:171","msg":"trace[403357444] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"202.112556ms","start":"2026-06-10T08:26:51.649953Z","end":"2026-06-10T08:26:51.852066Z","steps":["trace[403357444] 'process raft request' (duration: 201.868021ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:26:51.852245Z","caller":"traceutil/trace.go:171","msg":"trace[990799484] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"202.192987ms","start":"2026-06-10T08:26:51.650016Z","end":"2026-06-10T08:26:51.852209Z","steps":["trace[990799484] 'process raft request' (duration: 202.001664ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:27:19.843556Z","caller":"traceutil/trace.go:171","msg":"trace[951640262] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"103.171927ms","start":"2026-06-10T08:27:19.74034Z","end":"2026-06-10T08:27:19.843512Z","steps":["trace[951640262] 'process raft request' (duration: 102.832438ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:27:22.068252Z","caller":"traceutil/trace.go:171","msg":"trace[1820800433] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"105.376096ms","start":"2026-06-10T08:27:21.962848Z","end":"2026-06-10T08:27:22.068224Z","steps":["trace[1820800433] 'process raft request' (duration: 105.112517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:28:02.296619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.71707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-10T08:28:02.296685Z","caller":"traceutil/trace.go:171","msg":"trace[961622600] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2733; }","duration":"110.826712ms","start":"2026-06-10T08:28:02.185845Z","end":"2026-06-10T08:28:02.296671Z","steps":["trace[961622600] 'range keys from in-memory index tree' (duration: 110.555366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:28:02.369257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.017366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:28:02.369351Z","caller":"traceutil/trace.go:171","msg":"trace[2028657262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2734; }","duration":"131.17194ms","start":"2026-06-10T08:28:02.238163Z","end":"2026-06-10T08:28:02.369335Z","steps":["trace[2028657262] 'agreement among raft nodes before linearized reading' (duration: 130.904013ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:28:02.369557Z","caller":"traceutil/trace.go:171","msg":"trace[902843920] linearizableReadLoop","detail":"{readStateIndex:2833; appliedIndex:2832; }","duration":"130.703537ms","start":"2026-06-10T08:28:02.238209Z","end":"2026-06-10T08:28:02.368912Z","steps":["trace[902843920] 'read index received' (duration: 130.431451ms)","trace[902843920] 'applied index is now lower than readState.Index' (duration: 271.296µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:28:02.369633Z","caller":"traceutil/trace.go:171","msg":"trace[676089967] transaction","detail":"{read_only:false; response_revision:2734; number_of_response:1; }","duration":"138.358225ms","start":"2026-06-10T08:28:02.23066Z","end":"2026-06-10T08:28:02.369018Z","steps":["trace[676089967] 'process raft request' (duration: 138.086758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:28:02.369869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.420105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-06-10T08:28:02.36991Z","caller":"traceutil/trace.go:171","msg":"trace[1026691055] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2734; }","duration":"102.496757ms","start":"2026-06-10T08:28:02.267399Z","end":"2026-06-10T08:28:02.369896Z","steps":["trace[1026691055] 'agreement among raft nodes before linearized reading' (duration: 102.328082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:28:02.370149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.88619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T08:28:02.37019Z","caller":"traceutil/trace.go:171","msg":"trace[2108507221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2734; }","duration":"101.969093ms","start":"2026-06-10T08:28:02.268209Z","end":"2026-06-10T08:28:02.370179Z","steps":["trace[2108507221] 'agreement among raft nodes before linearized reading' (duration: 101.857381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:28:02.902027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.291622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:28:02.902126Z","caller":"traceutil/trace.go:171","msg":"trace[1105726132] transaction","detail":"{read_only:false; response_revision:2735; number_of_response:1; }","duration":"528.51814ms","start":"2026-06-10T08:28:02.373589Z","end":"2026-06-10T08:28:02.902107Z","steps":["trace[1105726132] 'process raft request' (duration: 235.074704ms)","trace[1105726132] 'compare' (duration: 292.866501ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:28:02.902184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:28:02.373575Z","time spent":"528.581891ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:28:02.902864Z","caller":"traceutil/trace.go:171","msg":"trace[609227156] linearizableReadLoop","detail":"{readStateIndex:2836; appliedIndex:2833; }","duration":"526.367454ms","start":"2026-06-10T08:28:02.376484Z","end":"2026-06-10T08:28:02.902852Z","steps":["trace[609227156] 'read index received' (duration: 232.24626ms)","trace[609227156] 'applied index is now lower than readState.Index' (duration: 294.120424ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:28:02.903046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.564629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7341"} {"level":"info","ts":"2026-06-10T08:28:02.903075Z","caller":"traceutil/trace.go:171","msg":"trace[765531554] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2737; }","duration":"526.622641ms","start":"2026-06-10T08:28:02.376443Z","end":"2026-06-10T08:28:02.903065Z","steps":["trace[765531554] 'agreement among raft nodes before linearized reading' (duration: 526.464497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:28:02.903103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:28:02.376432Z","time spent":"526.664931ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7365,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"info","ts":"2026-06-10T08:28:02.903252Z","caller":"traceutil/trace.go:171","msg":"trace[1639467678] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"529.401502ms","start":"2026-06-10T08:28:02.373812Z","end":"2026-06-10T08:28:02.903213Z","steps":["trace[1639467678] 'process raft request' (duration: 528.860789ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:28:02.9033Z","caller":"traceutil/trace.go:171","msg":"trace[294615106] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"527.537945ms","start":"2026-06-10T08:28:02.375751Z","end":"2026-06-10T08:28:02.903289Z","steps":["trace[294615106] 'process raft request' (duration: 527.061552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:28:02.903367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:28:02.375722Z","time spent":"527.606647ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:28:02.903423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:28:02.373778Z","time spent":"529.560777ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:28:23.390155Z","caller":"traceutil/trace.go:171","msg":"trace[655674704] linearizableReadLoop","detail":"{readStateIndex:2945; appliedIndex:2944; }","duration":"136.173569ms","start":"2026-06-10T08:28:23.253963Z","end":"2026-06-10T08:28:23.390136Z","steps":["trace[655674704] 'read index received' (duration: 136.117248ms)","trace[655674704] 'applied index is now lower than readState.Index' (duration: 55.021µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:28:23.390354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.373934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:28:23.390412Z","caller":"traceutil/trace.go:171","msg":"trace[482237056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2841; }","duration":"136.488706ms","start":"2026-06-10T08:28:23.25391Z","end":"2026-06-10T08:28:23.390399Z","steps":["trace[482237056] 'agreement among raft nodes before linearized reading' (duration: 136.350603ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:28:23.627556Z","caller":"traceutil/trace.go:171","msg":"trace[1762734895] linearizableReadLoop","detail":"{readStateIndex:2948; appliedIndex:2947; }","duration":"147.63565ms","start":"2026-06-10T08:28:23.479894Z","end":"2026-06-10T08:28:23.62753Z","steps":["trace[1762734895] 'read index received' (duration: 121.438196ms)","trace[1762734895] 'applied index is now lower than readState.Index' (duration: 26.196404ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:28:23.627633Z","caller":"traceutil/trace.go:171","msg":"trace[1572427825] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"195.108278ms","start":"2026-06-10T08:28:23.432489Z","end":"2026-06-10T08:28:23.627597Z","steps":["trace[1572427825] 'process raft request' (duration: 168.908063ms)","trace[1572427825] 'compare' (duration: 26.013021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:28:23.627815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.907126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-10T08:28:23.627859Z","caller":"traceutil/trace.go:171","msg":"trace[1767284262] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2844; }","duration":"148.008099ms","start":"2026-06-10T08:28:23.479839Z","end":"2026-06-10T08:28:23.627847Z","steps":["trace[1767284262] 'agreement among raft nodes before linearized reading' (duration: 147.856065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:34.576045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.250292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:31:34.576547Z","caller":"traceutil/trace.go:171","msg":"trace[430459623] linearizableReadLoop","detail":"{readStateIndex:4712; appliedIndex:4710; }","duration":"437.903061ms","start":"2026-06-10T08:31:34.138624Z","end":"2026-06-10T08:31:34.576527Z","steps":["trace[430459623] 'read index received' (duration: 265.817992ms)","trace[430459623] 'applied index is now lower than readState.Index' (duration: 172.083999ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:31:34.576632Z","caller":"traceutil/trace.go:171","msg":"trace[1385696544] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"443.867152ms","start":"2026-06-10T08:31:34.132758Z","end":"2026-06-10T08:31:34.576625Z","steps":["trace[1385696544] 'process raft request' (duration: 443.544026ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:31:34.576635Z","caller":"traceutil/trace.go:171","msg":"trace[1765378412] transaction","detail":"{read_only:false; response_revision:4544; number_of_response:1; }","duration":"299.218371ms","start":"2026-06-10T08:31:34.277406Z","end":"2026-06-10T08:31:34.576625Z","steps":["trace[1765378412] 'process raft request' (duration: 299.070448ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:34.576722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:34.132737Z","time spent":"443.927933ms","remote":"127.0.0.1:42846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:31:34.576539Z","caller":"traceutil/trace.go:171","msg":"trace[1877076334] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"492.67632ms","start":"2026-06-10T08:31:34.083788Z","end":"2026-06-10T08:31:34.576464Z","steps":["trace[1877076334] 'process raft request' (duration: 320.643173ms)","trace[1877076334] 'compare' (duration: 170.779394ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:31:34.577096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:34.083765Z","time spent":"493.270101ms","remote":"127.0.0.1:45950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2471,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:31:34.577192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.4659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:31:34.577225Z","caller":"traceutil/trace.go:171","msg":"trace[616563043] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:4544; }","duration":"109.542382ms","start":"2026-06-10T08:31:34.467675Z","end":"2026-06-10T08:31:34.577218Z","steps":["trace[616563043] 'agreement among raft nodes before linearized reading' (duration: 109.479289ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:31:34.576567Z","caller":"traceutil/trace.go:171","msg":"trace[62080864] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"372.974779ms","start":"2026-06-10T08:31:34.203567Z","end":"2026-06-10T08:31:34.576541Z","steps":["trace[62080864] 'process raft request' (duration: 372.843326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:34.577322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.836033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:31:34.577347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:34.203549Z","time spent":"373.747523ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:31:34.577384Z","caller":"traceutil/trace.go:171","msg":"trace[356266349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4544; }","duration":"315.883814ms","start":"2026-06-10T08:31:34.261474Z","end":"2026-06-10T08:31:34.577358Z","steps":["trace[356266349] 'agreement among raft nodes before linearized reading' (duration: 315.811183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:34.577437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:34.26142Z","time spent":"316.004177ms","remote":"127.0.0.1:42770","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-06-10T08:31:34.577209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.934757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-06-10T08:31:34.577597Z","caller":"traceutil/trace.go:171","msg":"trace[362583231] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4544; }","duration":"348.377436ms","start":"2026-06-10T08:31:34.2292Z","end":"2026-06-10T08:31:34.577577Z","steps":["trace[362583231] 'agreement among raft nodes before linearized reading' (duration: 347.832415ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:34.577649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:34.229177Z","time spent":"348.458117ms","remote":"127.0.0.1:42960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-06-10T08:31:34.579762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.936819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14496"} {"level":"info","ts":"2026-06-10T08:31:34.579849Z","caller":"traceutil/trace.go:171","msg":"trace[1358386544] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4544; }","duration":"441.245363ms","start":"2026-06-10T08:31:34.138588Z","end":"2026-06-10T08:31:34.579834Z","steps":["trace[1358386544] 'agreement among raft nodes before linearized reading' (duration: 438.242427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:34.579964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:34.138575Z","time spent":"441.374115ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":14520,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-06-10T08:31:39.909457Z","caller":"traceutil/trace.go:171","msg":"trace[127165906] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"264.552921ms","start":"2026-06-10T08:31:39.644881Z","end":"2026-06-10T08:31:39.909434Z","steps":["trace[127165906] 'process raft request' (duration: 264.349668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:52.391552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:52.047484Z","time spent":"344.058278ms","remote":"127.0.0.1:41280","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-10T08:31:52.391923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.84188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-10T08:31:52.391991Z","caller":"traceutil/trace.go:171","msg":"trace[952121608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4819; }","duration":"332.917311ms","start":"2026-06-10T08:31:52.059055Z","end":"2026-06-10T08:31:52.391972Z","steps":["trace[952121608] 'range keys from in-memory index tree' (duration: 332.660746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:52.392028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:52.058959Z","time spent":"333.060184ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:31:52.392232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.38701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:31:52.392966Z","caller":"traceutil/trace.go:171","msg":"trace[1958544243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4819; }","duration":"135.211154ms","start":"2026-06-10T08:31:52.257728Z","end":"2026-06-10T08:31:52.39294Z","steps":["trace[1958544243] 'range keys from in-memory index tree' (duration: 134.286797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:52.392307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.908182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-10T08:31:52.393265Z","caller":"traceutil/trace.go:171","msg":"trace[1516226920] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4819; }","duration":"375.87471ms","start":"2026-06-10T08:31:52.017368Z","end":"2026-06-10T08:31:52.393242Z","steps":["trace[1516226920] 'range keys from in-memory index tree' (duration: 374.523285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:52.393378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:52.017339Z","time spent":"376.019023ms","remote":"127.0.0.1:42960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-10T08:31:52.392403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.978343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-10T08:31:52.393623Z","caller":"traceutil/trace.go:171","msg":"trace[1036686279] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4819; }","duration":"234.221065ms","start":"2026-06-10T08:31:52.159385Z","end":"2026-06-10T08:31:52.393607Z","steps":["trace[1036686279] 'range keys from in-memory index tree' (duration: 232.314281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:52.392457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.180026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:31:52.393766Z","caller":"traceutil/trace.go:171","msg":"trace[403036561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4819; }","duration":"335.489449ms","start":"2026-06-10T08:31:52.058243Z","end":"2026-06-10T08:31:52.393732Z","steps":["trace[403036561] 'range keys from in-memory index tree' (duration: 333.396141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:52.392496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.227896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"warn","ts":"2026-06-10T08:31:52.393875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:52.058228Z","time spent":"335.602622ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T08:31:52.393883Z","caller":"traceutil/trace.go:171","msg":"trace[1118228728] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4819; }","duration":"406.629473ms","start":"2026-06-10T08:31:51.987235Z","end":"2026-06-10T08:31:52.393864Z","steps":["trace[1118228728] 'range keys from in-memory index tree' (duration: 404.373661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:31:52.394019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:31:51.987215Z","time spent":"406.751985ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10418,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-06-10T08:31:52.53773Z","caller":"traceutil/trace.go:171","msg":"trace[1058799935] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"139.840281ms","start":"2026-06-10T08:31:52.397864Z","end":"2026-06-10T08:31:52.537704Z","steps":["trace[1058799935] 'process raft request' (duration: 139.633747ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:31:52.537934Z","caller":"traceutil/trace.go:171","msg":"trace[12636564] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4997; }","duration":"139.930062ms","start":"2026-06-10T08:31:52.397991Z","end":"2026-06-10T08:31:52.537921Z","steps":["trace[12636564] 'read index received' (duration: 12.642825ms)","trace[12636564] 'applied index is now lower than readState.Index' (duration: 127.286277ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:31:52.538107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.103745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-cd53a900-7125-4aac-9f2e-1a16b273fa9f\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-06-10T08:31:52.538368Z","caller":"traceutil/trace.go:171","msg":"trace[1458678435] range","detail":"{range_begin:/registry/persistentvolumes/pvc-cd53a900-7125-4aac-9f2e-1a16b273fa9f; range_end:; response_count:1; response_revision:4823; }","duration":"140.384082ms","start":"2026-06-10T08:31:52.397959Z","end":"2026-06-10T08:31:52.538343Z","steps":["trace[1458678435] 'agreement among raft nodes before linearized reading' (duration: 139.996844ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:31:52.538097Z","caller":"traceutil/trace.go:171","msg":"trace[1642212733] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"134.91804ms","start":"2026-06-10T08:31:52.403154Z","end":"2026-06-10T08:31:52.538072Z","steps":["trace[1642212733] 'process raft request' (duration: 134.697095ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:31:52.538157Z","caller":"traceutil/trace.go:171","msg":"trace[641818860] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"138.760651ms","start":"2026-06-10T08:31:52.399387Z","end":"2026-06-10T08:31:52.538147Z","steps":["trace[641818860] 'process raft request' (duration: 138.241161ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:32:49.862084Z","caller":"traceutil/trace.go:171","msg":"trace[253175344] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"109.929718ms","start":"2026-06-10T08:32:49.752119Z","end":"2026-06-10T08:32:49.862049Z","steps":["trace[253175344] 'process raft request' (duration: 109.776904ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:30.145962Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2878} {"level":"info","ts":"2026-06-10T08:33:30.215237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2878,"took":"67.87821ms","hash":1897363666,"current-db-size-bytes":52903936,"current-db-size":"53 MB","current-db-size-in-use-bytes":28426240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-10T08:33:30.215298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897363666,"revision":2878,"compact-revision":-1} {"level":"warn","ts":"2026-06-10T08:33:33.5968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096969612420091,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:33:34.096968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096969612420091,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:33:34.205498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143124233s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T08:33:34.207233Z","caller":"traceutil/trace.go:171","msg":"trace[1732801851] linearizableReadLoop","detail":"{readStateIndex:5682; appliedIndex:5679; }","duration":"1.110732388s","start":"2026-06-10T08:33:33.096475Z","end":"2026-06-10T08:33:34.207208Z","steps":["trace[1732801851] 'read index received' (duration: 1.109258792s)","trace[1732801851] 'applied index is now lower than readState.Index' (duration: 1.472346ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:34.207381Z","caller":"traceutil/trace.go:171","msg":"trace[1182401241] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"643.858163ms","start":"2026-06-10T08:33:33.563505Z","end":"2026-06-10T08:33:34.207363Z","steps":["trace[1182401241] 'process raft request' (duration: 643.656359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.207489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.563493Z","time spent":"643.920163ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:34.207627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111149685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:34.207664Z","caller":"traceutil/trace.go:171","msg":"trace[1076405820] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5482; }","duration":"1.111210695s","start":"2026-06-10T08:33:33.096443Z","end":"2026-06-10T08:33:34.207654Z","steps":["trace[1076405820] 'agreement among raft nodes before linearized reading' (duration: 1.111156864s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.207691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.096428Z","time spent":"1.111257046s","remote":"127.0.0.1:43054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"warn","ts":"2026-06-10T08:33:34.207927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111315738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:34.207982Z","caller":"traceutil/trace.go:171","msg":"trace[1317018693] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5482; }","duration":"1.111399309s","start":"2026-06-10T08:33:33.096572Z","end":"2026-06-10T08:33:34.207971Z","steps":["trace[1317018693] 'agreement among raft nodes before linearized reading' (duration: 1.111305318s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.208006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.096564Z","time spent":"1.11143626s","remote":"127.0.0.1:43054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"info","ts":"2026-06-10T08:33:34.207952Z","caller":"traceutil/trace.go:171","msg":"trace[460906042] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"1.14566759s","start":"2026-06-10T08:33:33.062262Z","end":"2026-06-10T08:33:34.20793Z","steps":["trace[460906042] 'process raft request' (duration: 1.143459111s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.208273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.062245Z","time spent":"1.145953784s","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:34.208406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.665265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-10T08:33:34.208435Z","caller":"traceutil/trace.go:171","msg":"trace[1887087417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5482; }","duration":"144.742037ms","start":"2026-06-10T08:33:34.063685Z","end":"2026-06-10T08:33:34.208427Z","steps":["trace[1887087417] 'agreement among raft nodes before linearized reading' (duration: 144.651124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.208561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.815072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-10T08:33:34.208587Z","caller":"traceutil/trace.go:171","msg":"trace[92671216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5482; }","duration":"393.882292ms","start":"2026-06-10T08:33:33.814696Z","end":"2026-06-10T08:33:34.208578Z","steps":["trace[92671216] 'agreement among raft nodes before linearized reading' (duration: 393.819871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.208605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.814673Z","time spent":"393.926644ms","remote":"127.0.0.1:43072","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-06-10T08:33:34.208667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.391574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:33:34.208688Z","caller":"traceutil/trace.go:171","msg":"trace[1828933435] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:5482; }","duration":"539.449144ms","start":"2026-06-10T08:33:33.669233Z","end":"2026-06-10T08:33:34.208682Z","steps":["trace[1828933435] 'agreement among raft nodes before linearized reading' (duration: 539.411573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.208705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.669213Z","time spent":"539.487335ms","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":4,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:33:34.208822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.247105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:33:34.208846Z","caller":"traceutil/trace.go:171","msg":"trace[1788289219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5482; }","duration":"646.296306ms","start":"2026-06-10T08:33:33.562542Z","end":"2026-06-10T08:33:34.208838Z","steps":["trace[1788289219] 'agreement among raft nodes before linearized reading' (duration: 646.236985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.208864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.562525Z","time spent":"646.334536ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:33:34.208925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.640339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:34.208982Z","caller":"traceutil/trace.go:171","msg":"trace[2120925586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5482; }","duration":"953.725011ms","start":"2026-06-10T08:33:33.255244Z","end":"2026-06-10T08:33:34.208969Z","steps":["trace[2120925586] 'agreement among raft nodes before linearized reading' (duration: 953.642309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.209013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.255227Z","time spent":"953.778401ms","remote":"127.0.0.1:42770","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-06-10T08:33:34.209253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00255946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-10T08:33:34.209291Z","caller":"traceutil/trace.go:171","msg":"trace[669102375] range","detail":"{range_begin:/registry/masterleases/199.19.213.35; range_end:; response_count:1; response_revision:5482; }","duration":"1.002632572s","start":"2026-06-10T08:33:33.206648Z","end":"2026-06-10T08:33:34.209281Z","steps":["trace[669102375] 'agreement among raft nodes before linearized reading' (duration: 1.0025008s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.209316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.206629Z","time spent":"1.002681912s","remote":"127.0.0.1:42786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.35\" "} {"level":"info","ts":"2026-06-10T08:33:34.207303Z","caller":"traceutil/trace.go:171","msg":"trace[1973935090] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"647.553439ms","start":"2026-06-10T08:33:33.559696Z","end":"2026-06-10T08:33:34.20725Z","steps":["trace[1973935090] 'process raft request' (duration: 647.372126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.209502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.064732108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-06-10T08:33:34.209542Z","caller":"traceutil/trace.go:171","msg":"trace[875240569] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5482; }","duration":"1.064808739s","start":"2026-06-10T08:33:33.144723Z","end":"2026-06-10T08:33:34.209532Z","steps":["trace[875240569] 'agreement among raft nodes before linearized reading' (duration: 1.064716797s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:34.209598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.144701Z","time spent":"1.064867451s","remote":"127.0.0.1:43054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6183,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"warn","ts":"2026-06-10T08:33:34.209553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:33.559671Z","time spent":"649.732137ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:35.535747Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15855096969612420110,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:33:35.568907Z","caller":"traceutil/trace.go:171","msg":"trace[189493007] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"627.687773ms","start":"2026-06-10T08:33:34.941183Z","end":"2026-06-10T08:33:35.568871Z","steps":["trace[189493007] 'process raft request' (duration: 627.49652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:35.569081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:34.941145Z","time spent":"627.858455ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:33:35.569692Z","caller":"traceutil/trace.go:171","msg":"trace[342707785] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"528.209612ms","start":"2026-06-10T08:33:35.041459Z","end":"2026-06-10T08:33:35.569669Z","steps":["trace[342707785] 'process raft request' (duration: 528.07346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:35.569829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:35.041437Z","time spent":"528.308603ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:33:35.569961Z","caller":"traceutil/trace.go:171","msg":"trace[1834503017] linearizableReadLoop","detail":"{readStateIndex:5690; appliedIndex:5687; }","duration":"534.8672ms","start":"2026-06-10T08:33:35.035084Z","end":"2026-06-10T08:33:35.569952Z","steps":["trace[1834503017] 'read index received' (duration: 533.625568ms)","trace[1834503017] 'applied index is now lower than readState.Index' (duration: 1.240902ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:35.570071Z","caller":"traceutil/trace.go:171","msg":"trace[1562213366] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"527.47121ms","start":"2026-06-10T08:33:35.04259Z","end":"2026-06-10T08:33:35.570062Z","steps":["trace[1562213366] 'process raft request' (duration: 527.044832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:35.570124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:35.042572Z","time spent":"527.52504ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:33:35.570309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.217626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:33:35.570369Z","caller":"traceutil/trace.go:171","msg":"trace[1395626289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5489; }","duration":"535.306058ms","start":"2026-06-10T08:33:35.03505Z","end":"2026-06-10T08:33:35.570356Z","steps":["trace[1395626289] 'agreement among raft nodes before linearized reading' (duration: 535.150706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:35.570413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:35.03503Z","time spent":"535.374309ms","remote":"127.0.0.1:43072","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-06-10T08:33:35.570568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.07205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:33:35.570643Z","caller":"traceutil/trace.go:171","msg":"trace[695333529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5489; }","duration":"314.182022ms","start":"2026-06-10T08:33:35.256443Z","end":"2026-06-10T08:33:35.570625Z","steps":["trace[695333529] 'agreement among raft nodes before linearized reading' (duration: 314.07755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:35.570691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:33:35.256425Z","time spent":"314.255443ms","remote":"127.0.0.1:42780","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-06-10T08:33:35.570734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.489823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:33:35.570778Z","caller":"traceutil/trace.go:171","msg":"trace[1545860776] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:5489; }","duration":"261.561076ms","start":"2026-06-10T08:33:35.309204Z","end":"2026-06-10T08:33:35.570766Z","steps":["trace[1545860776] 'agreement among raft nodes before linearized reading' (duration: 261.488644ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:33:45.722163Z","caller":"traceutil/trace.go:171","msg":"trace[789157321] linearizableReadLoop","detail":"{readStateIndex:5745; appliedIndex:5743; }","duration":"109.583076ms","start":"2026-06-10T08:33:45.612558Z","end":"2026-06-10T08:33:45.722141Z","steps":["trace[789157321] 'read index received' (duration: 37.443915ms)","trace[789157321] 'applied index is now lower than readState.Index' (duration: 72.138341ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:33:45.72225Z","caller":"traceutil/trace.go:171","msg":"trace[527155916] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"118.776448ms","start":"2026-06-10T08:33:45.603459Z","end":"2026-06-10T08:33:45.722235Z","steps":["trace[527155916] 'process raft request' (duration: 116.445126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:45.72242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.83842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-10T08:33:45.722671Z","caller":"traceutil/trace.go:171","msg":"trace[1184813153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5542; }","duration":"110.110224ms","start":"2026-06-10T08:33:45.612523Z","end":"2026-06-10T08:33:45.722634Z","steps":["trace[1184813153] 'agreement among raft nodes before linearized reading' (duration: 109.739258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:33:45.722495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.847484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:33:45.722793Z","caller":"traceutil/trace.go:171","msg":"trace[1537069370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5542; }","duration":"108.184131ms","start":"2026-06-10T08:33:45.614593Z","end":"2026-06-10T08:33:45.722777Z","steps":["trace[1537069370] 'agreement among raft nodes before linearized reading' (duration: 107.770253ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:06.196859Z","caller":"traceutil/trace.go:171","msg":"trace[1350362410] transaction","detail":"{read_only:false; response_revision:6094; number_of_response:1; }","duration":"127.898535ms","start":"2026-06-10T08:35:06.068922Z","end":"2026-06-10T08:35:06.196821Z","steps":["trace[1350362410] 'process raft request' (duration: 68.784351ms)","trace[1350362410] 'compare' (duration: 58.928441ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:35:59.456377Z","caller":"traceutil/trace.go:171","msg":"trace[74512262] linearizableReadLoop","detail":"{readStateIndex:6677; appliedIndex:6676; }","duration":"227.281535ms","start":"2026-06-10T08:35:59.229075Z","end":"2026-06-10T08:35:59.456357Z","steps":["trace[74512262] 'read index received' (duration: 227.110708ms)","trace[74512262] 'applied index is now lower than readState.Index' (duration: 170.167µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:35:59.45669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.389401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-10T08:35:59.456692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.594246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-06-10T08:35:59.456728Z","caller":"traceutil/trace.go:171","msg":"trace[907421339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6442; }","duration":"102.481204ms","start":"2026-06-10T08:35:59.354238Z","end":"2026-06-10T08:35:59.456719Z","steps":["trace[907421339] 'agreement among raft nodes before linearized reading' (duration: 102.347329ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:59.456751Z","caller":"traceutil/trace.go:171","msg":"trace[544852590] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:6442; }","duration":"227.706959ms","start":"2026-06-10T08:35:59.229028Z","end":"2026-06-10T08:35:59.456735Z","steps":["trace[544852590] 'agreement among raft nodes before linearized reading' (duration: 227.475872ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:35:59.45666Z","caller":"traceutil/trace.go:171","msg":"trace[657273411] transaction","detail":"{read_only:false; response_revision:6442; number_of_response:1; }","duration":"229.662176ms","start":"2026-06-10T08:35:59.226975Z","end":"2026-06-10T08:35:59.456637Z","steps":["trace[657273411] 'process raft request' (duration: 229.232971ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:36:31.430972Z","caller":"traceutil/trace.go:171","msg":"trace[1677249724] transaction","detail":"{read_only:false; response_revision:6736; number_of_response:1; }","duration":"139.451496ms","start":"2026-06-10T08:36:31.291501Z","end":"2026-06-10T08:36:31.430952Z","steps":["trace[1677249724] 'process raft request' (duration: 139.136586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:36:49.40886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.205606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:36:49.408953Z","caller":"traceutil/trace.go:171","msg":"trace[1904939927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6881; }","duration":"154.342831ms","start":"2026-06-10T08:36:49.254592Z","end":"2026-06-10T08:36:49.408935Z","steps":["trace[1904939927] 'range keys from in-memory index tree' (duration: 154.140725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:36:49.40924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.003142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:36:49.409279Z","caller":"traceutil/trace.go:171","msg":"trace[220324604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6881; }","duration":"148.091135ms","start":"2026-06-10T08:36:49.261178Z","end":"2026-06-10T08:36:49.409269Z","steps":["trace[220324604] 'range keys from in-memory index tree' (duration: 147.868017ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:37:36.330276Z","caller":"traceutil/trace.go:171","msg":"trace[1488632989] transaction","detail":"{read_only:false; response_revision:7141; number_of_response:1; }","duration":"946.220248ms","start":"2026-06-10T08:37:35.384037Z","end":"2026-06-10T08:37:36.330257Z","steps":["trace[1488632989] 'process raft request' (duration: 945.251889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:37:36.330445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:37:35.384018Z","time spent":"946.342622ms","remote":"127.0.0.1:43072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:38:30.152043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5465} {"level":"info","ts":"2026-06-10T08:38:30.228667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5465,"took":"74.968519ms","hash":1465986374,"current-db-size-bytes":52903936,"current-db-size":"53 MB","current-db-size-in-use-bytes":18235392,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-10T08:38:30.228727Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1465986374,"revision":5465,"compact-revision":2878} {"level":"info","ts":"2026-06-10T08:39:45.13089Z","caller":"traceutil/trace.go:171","msg":"trace[778528960] linearizableReadLoop","detail":"{readStateIndex:8092; appliedIndex:8091; }","duration":"107.181351ms","start":"2026-06-10T08:39:45.023685Z","end":"2026-06-10T08:39:45.130866Z","steps":["trace[778528960] 'read index received' (duration: 107.020578ms)","trace[778528960] 'applied index is now lower than readState.Index' (duration: 159.873µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:45.130949Z","caller":"traceutil/trace.go:171","msg":"trace[475970068] transaction","detail":"{read_only:false; response_revision:7804; number_of_response:1; }","duration":"109.192412ms","start":"2026-06-10T08:39:45.02171Z","end":"2026-06-10T08:39:45.130902Z","steps":["trace[475970068] 'process raft request' (duration: 108.976827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:45.131219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.51649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/memcached-memcached-bin\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-06-10T08:39:45.131262Z","caller":"traceutil/trace.go:171","msg":"trace[499765627] range","detail":"{range_begin:/registry/configmaps/openstack/memcached-memcached-bin; range_end:; response_count:1; response_revision:7804; }","duration":"107.602393ms","start":"2026-06-10T08:39:45.023647Z","end":"2026-06-10T08:39:45.13125Z","steps":["trace[499765627] 'agreement among raft nodes before linearized reading' (duration: 107.398737ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:47.145414Z","caller":"traceutil/trace.go:171","msg":"trace[652221958] transaction","detail":"{read_only:false; response_revision:7812; number_of_response:1; }","duration":"153.080025ms","start":"2026-06-10T08:39:46.992282Z","end":"2026-06-10T08:39:47.145362Z","steps":["trace[652221958] 'process raft request' (duration: 103.127133ms)","trace[652221958] 'compare' (duration: 49.786187ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:47.145591Z","caller":"traceutil/trace.go:171","msg":"trace[71649402] linearizableReadLoop","detail":"{readStateIndex:8101; appliedIndex:8099; }","duration":"147.464471ms","start":"2026-06-10T08:39:46.998009Z","end":"2026-06-10T08:39:47.145473Z","steps":["trace[71649402] 'read index received' (duration: 24.267377ms)","trace[71649402] 'applied index is now lower than readState.Index' (duration: 123.185964ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:39:47.145685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.654747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/horizon\" ","response":"range_response_count:1 size:9004"} {"level":"info","ts":"2026-06-10T08:39:47.14544Z","caller":"traceutil/trace.go:171","msg":"trace[907717574] transaction","detail":"{read_only:false; response_revision:7813; number_of_response:1; }","duration":"153.002993ms","start":"2026-06-10T08:39:46.992407Z","end":"2026-06-10T08:39:47.14541Z","steps":["trace[907717574] 'process raft request' (duration: 152.8761ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:47.146838Z","caller":"traceutil/trace.go:171","msg":"trace[242561022] range","detail":"{range_begin:/registry/deployments/openstack/horizon; range_end:; response_count:1; response_revision:7814; }","duration":"148.802586ms","start":"2026-06-10T08:39:46.998002Z","end":"2026-06-10T08:39:47.146805Z","steps":["trace[242561022] 'agreement among raft nodes before linearized reading' (duration: 147.546564ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:47.35397Z","caller":"traceutil/trace.go:171","msg":"trace[1365920590] linearizableReadLoop","detail":"{readStateIndex:8104; appliedIndex:8102; }","duration":"207.762295ms","start":"2026-06-10T08:39:47.146179Z","end":"2026-06-10T08:39:47.353941Z","steps":["trace[1365920590] 'read index received' (duration: 53.188463ms)","trace[1365920590] 'applied index is now lower than readState.Index' (duration: 154.572782ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:47.35403Z","caller":"traceutil/trace.go:171","msg":"trace[818573324] transaction","detail":"{read_only:false; response_revision:7816; number_of_response:1; }","duration":"208.957305ms","start":"2026-06-10T08:39:47.145031Z","end":"2026-06-10T08:39:47.353989Z","steps":["trace[818573324] 'process raft request' (duration: 187.15172ms)","trace[818573324] 'compare' (duration: 21.64259ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:39:47.354119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.653715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:39:47.354188Z","caller":"traceutil/trace.go:171","msg":"trace[1708117172] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:7816; }","duration":"213.755827ms","start":"2026-06-10T08:39:47.140415Z","end":"2026-06-10T08:39:47.354171Z","steps":["trace[1708117172] 'agreement among raft nodes before linearized reading' (duration: 213.602233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:47.354237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.609848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/keystone-api\" ","response":"range_response_count:1 size:11490"} {"level":"info","ts":"2026-06-10T08:39:47.354317Z","caller":"traceutil/trace.go:171","msg":"trace[249847875] range","detail":"{range_begin:/registry/deployments/openstack/keystone-api; range_end:; response_count:1; response_revision:7816; }","duration":"348.731151ms","start":"2026-06-10T08:39:47.00557Z","end":"2026-06-10T08:39:47.354301Z","steps":["trace[249847875] 'agreement among raft nodes before linearized reading' (duration: 348.492395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:47.354362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:39:47.005551Z","time spent":"348.802752ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":11514,"request content":"key:\"/registry/deployments/openstack/keystone-api\" "} {"level":"warn","ts":"2026-06-10T08:39:47.355568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.968052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:280 size:219805"} {"level":"info","ts":"2026-06-10T08:39:47.355621Z","caller":"traceutil/trace.go:171","msg":"trace[1542093563] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:280; response_revision:7816; }","duration":"203.060115ms","start":"2026-06-10T08:39:47.15255Z","end":"2026-06-10T08:39:47.35561Z","steps":["trace[1542093563] 'agreement among raft nodes before linearized reading' (duration: 201.545687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:47.649732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.66448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/memcached-memcached\" ","response":"range_response_count:1 size:7256"} {"level":"info","ts":"2026-06-10T08:39:47.649783Z","caller":"traceutil/trace.go:171","msg":"trace[2073626191] range","detail":"{range_begin:/registry/deployments/openstack/memcached-memcached; range_end:; response_count:1; response_revision:7816; }","duration":"122.748932ms","start":"2026-06-10T08:39:47.527023Z","end":"2026-06-10T08:39:47.649772Z","steps":["trace[2073626191] 'range keys from in-memory index tree' (duration: 122.516396ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:47.650133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.070119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/pxc-operator\" ","response":"range_response_count:1 size:4385"} {"level":"info","ts":"2026-06-10T08:39:47.650152Z","caller":"traceutil/trace.go:171","msg":"trace[2050148840] range","detail":"{range_begin:/registry/deployments/openstack/pxc-operator; range_end:; response_count:1; response_revision:7816; }","duration":"117.11904ms","start":"2026-06-10T08:39:47.533028Z","end":"2026-06-10T08:39:47.650147Z","steps":["trace[2050148840] 'range keys from in-memory index tree' (duration: 116.375341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:49.565574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.194835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:39:49.565989Z","caller":"traceutil/trace.go:171","msg":"trace[1521663594] linearizableReadLoop","detail":"{readStateIndex:8114; appliedIndex:8113; }","duration":"206.314569ms","start":"2026-06-10T08:39:49.359631Z","end":"2026-06-10T08:39:49.565945Z","steps":["trace[1521663594] 'read index received' (duration: 88.569609ms)","trace[1521663594] 'applied index is now lower than readState.Index' (duration: 117.74285ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:49.566138Z","caller":"traceutil/trace.go:171","msg":"trace[1063078406] transaction","detail":"{read_only:false; response_revision:7825; number_of_response:1; }","duration":"206.783759ms","start":"2026-06-10T08:39:49.359318Z","end":"2026-06-10T08:39:49.566102Z","steps":["trace[1063078406] 'process raft request' (duration: 88.959048ms)","trace[1063078406] 'compare' (duration: 117.041542ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:39:49.566276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.611266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-messaging-topology-operator-webhook\" ","response":"range_response_count:1 size:1245"} {"level":"info","ts":"2026-06-10T08:39:49.566357Z","caller":"traceutil/trace.go:171","msg":"trace[550146862] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-messaging-topology-operator-webhook; range_end:; response_count:1; response_revision:7825; }","duration":"206.73761ms","start":"2026-06-10T08:39:49.359598Z","end":"2026-06-10T08:39:49.566335Z","steps":["trace[550146862] 'agreement among raft nodes before linearized reading' (duration: 206.513774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:49.56685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.597468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:966"} {"level":"info","ts":"2026-06-10T08:39:49.56713Z","caller":"traceutil/trace.go:171","msg":"trace[1972692071] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:7825; }","duration":"201.749013ms","start":"2026-06-10T08:39:49.365199Z","end":"2026-06-10T08:39:49.566948Z","steps":["trace[1972692071] 'agreement among raft nodes before linearized reading' (duration: 201.387483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:49.788772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.48932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/secretgen-controller/secretgen-controller\" ","response":"range_response_count:1 size:3105"} {"level":"info","ts":"2026-06-10T08:39:49.78884Z","caller":"traceutil/trace.go:171","msg":"trace[446254999] range","detail":"{range_begin:/registry/deployments/secretgen-controller/secretgen-controller; range_end:; response_count:1; response_revision:7825; }","duration":"122.603124ms","start":"2026-06-10T08:39:49.666223Z","end":"2026-06-10T08:39:49.788826Z","steps":["trace[446254999] 'range keys from in-memory index tree' (duration: 122.336045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:51.047544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.515717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/secretgen-controller/secretgen-controller-5cf976ccc7-kh9l6\" ","response":"range_response_count:1 size:3159"} {"level":"info","ts":"2026-06-10T08:39:51.047634Z","caller":"traceutil/trace.go:171","msg":"trace[1008669814] range","detail":"{range_begin:/registry/pods/secretgen-controller/secretgen-controller-5cf976ccc7-kh9l6; range_end:; response_count:1; response_revision:7828; }","duration":"109.655851ms","start":"2026-06-10T08:39:50.937957Z","end":"2026-06-10T08:39:51.047613Z","steps":["trace[1008669814] 'range keys from in-memory index tree' (duration: 109.356992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:51.047554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.064531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1364"} {"level":"info","ts":"2026-06-10T08:39:51.047769Z","caller":"traceutil/trace.go:171","msg":"trace[41088302] range","detail":"{range_begin:/registry/networkpolicies/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:7828; }","duration":"112.312849ms","start":"2026-06-10T08:39:50.935438Z","end":"2026-06-10T08:39:51.047751Z","steps":["trace[41088302] 'range keys from in-memory index tree' (duration: 111.923558ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:51.375437Z","caller":"traceutil/trace.go:171","msg":"trace[1275161064] transaction","detail":"{read_only:false; response_revision:7832; number_of_response:1; }","duration":"133.962845ms","start":"2026-06-10T08:39:51.241453Z","end":"2026-06-10T08:39:51.375416Z","steps":["trace[1275161064] 'process raft request' (duration: 94.807793ms)","trace[1275161064] 'compare' (duration: 39.029769ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:51.375648Z","caller":"traceutil/trace.go:171","msg":"trace[1566323300] linearizableReadLoop","detail":"{readStateIndex:8121; appliedIndex:8120; }","duration":"121.15134ms","start":"2026-06-10T08:39:51.254444Z","end":"2026-06-10T08:39:51.375595Z","steps":["trace[1566323300] 'read index received' (duration: 76.018777ms)","trace[1566323300] 'applied index is now lower than readState.Index' (duration: 45.131383ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:39:51.375805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.345585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:39:51.375843Z","caller":"traceutil/trace.go:171","msg":"trace[1712349689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7833; }","duration":"121.431717ms","start":"2026-06-10T08:39:51.254402Z","end":"2026-06-10T08:39:51.375834Z","steps":["trace[1712349689] 'agreement among raft nodes before linearized reading' (duration: 121.351785ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:53.29181Z","caller":"traceutil/trace.go:171","msg":"trace[1383280994] linearizableReadLoop","detail":"{readStateIndex:8129; appliedIndex:8128; }","duration":"128.789018ms","start":"2026-06-10T08:39:53.162996Z","end":"2026-06-10T08:39:53.291785Z","steps":["trace[1383280994] 'read index received' (duration: 89.693699ms)","trace[1383280994] 'applied index is now lower than readState.Index' (duration: 39.094289ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:53.292143Z","caller":"traceutil/trace.go:171","msg":"trace[2117612169] transaction","detail":"{read_only:false; response_revision:7840; number_of_response:1; }","duration":"129.486635ms","start":"2026-06-10T08:39:53.162636Z","end":"2026-06-10T08:39:53.292123Z","steps":["trace[2117612169] 'process raft request' (duration: 90.059757ms)","trace[2117612169] 'compare' (duration: 38.981486ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:39:53.292555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.537697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-hfbc5\" ","response":"range_response_count:1 size:5603"} {"level":"info","ts":"2026-06-10T08:39:53.292613Z","caller":"traceutil/trace.go:171","msg":"trace[282141209] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-5448d56d95-hfbc5; range_end:; response_count:1; response_revision:7840; }","duration":"129.636778ms","start":"2026-06-10T08:39:53.162964Z","end":"2026-06-10T08:39:53.2926Z","steps":["trace[282141209] 'agreement among raft nodes before linearized reading' (duration: 129.517795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:53.293758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.633814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:280 size:219805"} {"level":"info","ts":"2026-06-10T08:39:53.293941Z","caller":"traceutil/trace.go:171","msg":"trace[920589578] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:280; response_revision:7840; }","duration":"128.827019ms","start":"2026-06-10T08:39:53.165097Z","end":"2026-06-10T08:39:53.293924Z","steps":["trace[920589578] 'agreement among raft nodes before linearized reading' (duration: 127.070745ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:55.620235Z","caller":"traceutil/trace.go:171","msg":"trace[1138286533] transaction","detail":"{read_only:false; response_revision:7854; number_of_response:1; }","duration":"139.355828ms","start":"2026-06-10T08:39:55.480832Z","end":"2026-06-10T08:39:55.620188Z","steps":["trace[1138286533] 'process raft request' (duration: 139.177314ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:55.666204Z","caller":"traceutil/trace.go:171","msg":"trace[1072010510] linearizableReadLoop","detail":"{readStateIndex:8146; appliedIndex:8143; }","duration":"125.15342ms","start":"2026-06-10T08:39:55.541024Z","end":"2026-06-10T08:39:55.666177Z","steps":["trace[1072010510] 'read index received' (duration: 79.022996ms)","trace[1072010510] 'applied index is now lower than readState.Index' (duration: 46.129634ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:55.666227Z","caller":"traceutil/trace.go:171","msg":"trace[212489303] transaction","detail":"{read_only:false; response_revision:7855; number_of_response:1; }","duration":"184.490719ms","start":"2026-06-10T08:39:55.481707Z","end":"2026-06-10T08:39:55.666198Z","steps":["trace[212489303] 'process raft request' (duration: 183.307398ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:55.66639Z","caller":"traceutil/trace.go:171","msg":"trace[1548868894] transaction","detail":"{read_only:false; response_revision:7856; number_of_response:1; }","duration":"145.79216ms","start":"2026-06-10T08:39:55.520562Z","end":"2026-06-10T08:39:55.666355Z","steps":["trace[1548868894] 'process raft request' (duration: 145.561365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:55.666719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.672742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/pxc-operator\" ","response":"range_response_count:1 size:971"} {"level":"info","ts":"2026-06-10T08:39:55.666795Z","caller":"traceutil/trace.go:171","msg":"trace[576814211] range","detail":"{range_begin:/registry/rolebindings/openstack/pxc-operator; range_end:; response_count:1; response_revision:7856; }","duration":"125.796495ms","start":"2026-06-10T08:39:55.54098Z","end":"2026-06-10T08:39:55.666777Z","steps":["trace[576814211] 'agreement among raft nodes before linearized reading' (duration: 125.431077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:55.812278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.077384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:39:55.812591Z","caller":"traceutil/trace.go:171","msg":"trace[1595312192] linearizableReadLoop","detail":"{readStateIndex:8147; appliedIndex:8146; }","duration":"146.310794ms","start":"2026-06-10T08:39:55.666255Z","end":"2026-06-10T08:39:55.812565Z","steps":["trace[1595312192] 'read index received' (duration: 36.8331ms)","trace[1595312192] 'applied index is now lower than readState.Index' (duration: 109.475164ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:55.812824Z","caller":"traceutil/trace.go:171","msg":"trace[1947118414] transaction","detail":"{read_only:false; response_revision:7857; number_of_response:1; }","duration":"161.97105ms","start":"2026-06-10T08:39:55.650681Z","end":"2026-06-10T08:39:55.812652Z","steps":["trace[1947118414] 'process raft request' (duration: 52.401153ms)","trace[1947118414] 'compare' (duration: 108.557951ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:39:55.812961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.646257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1239"} {"level":"info","ts":"2026-06-10T08:39:55.813142Z","caller":"traceutil/trace.go:171","msg":"trace[1890184166] range","detail":"{range_begin:/registry/rolebindings/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:7857; }","duration":"267.877152ms","start":"2026-06-10T08:39:55.545211Z","end":"2026-06-10T08:39:55.813089Z","steps":["trace[1890184166] 'agreement among raft nodes before linearized reading' (duration: 267.487063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:55.813722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.318308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/pxc-operator\" ","response":"range_response_count:1 size:971"} {"level":"info","ts":"2026-06-10T08:39:55.81386Z","caller":"traceutil/trace.go:171","msg":"trace[1552595433] range","detail":"{range_begin:/registry/rolebindings/openstack/pxc-operator; range_end:; response_count:1; response_revision:7857; }","duration":"143.656117ms","start":"2026-06-10T08:39:55.670118Z","end":"2026-06-10T08:39:55.813774Z","steps":["trace[1552595433] 'agreement among raft nodes before linearized reading' (duration: 142.777225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:56.054905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.543887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T08:39:56.054961Z","caller":"traceutil/trace.go:171","msg":"trace[1655751662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7857; }","duration":"121.63365ms","start":"2026-06-10T08:39:55.933314Z","end":"2026-06-10T08:39:56.054947Z","steps":["trace[1655751662] 'range keys from in-memory index tree' (duration: 121.406074ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:56.211063Z","caller":"traceutil/trace.go:171","msg":"trace[2038417908] transaction","detail":"{read_only:false; response_revision:7858; number_of_response:1; }","duration":"132.237155ms","start":"2026-06-10T08:39:56.078806Z","end":"2026-06-10T08:39:56.211043Z","steps":["trace[2038417908] 'process raft request' (duration: 131.801374ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:56.211275Z","caller":"traceutil/trace.go:171","msg":"trace[1980432087] transaction","detail":"{read_only:false; response_revision:7859; number_of_response:1; }","duration":"132.461451ms","start":"2026-06-10T08:39:56.078806Z","end":"2026-06-10T08:39:56.211268Z","steps":["trace[1980432087] 'process raft request' (duration: 131.929777ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:56.211423Z","caller":"traceutil/trace.go:171","msg":"trace[588093419] transaction","detail":"{read_only:false; response_revision:7860; number_of_response:1; }","duration":"132.606094ms","start":"2026-06-10T08:39:56.07881Z","end":"2026-06-10T08:39:56.211416Z","steps":["trace[588093419] 'process raft request' (duration: 131.962648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:57.142157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.414531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T08:39:57.142298Z","caller":"traceutil/trace.go:171","msg":"trace[74119082] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:7861; }","duration":"104.594816ms","start":"2026-06-10T08:39:57.037676Z","end":"2026-06-10T08:39:57.142271Z","steps":["trace[74119082] 'count revisions from in-memory index tree' (duration: 104.327769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:57.943234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.267247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/keystone-openstack-keystone-fernet-rotate\" ","response":"range_response_count:1 size:683"} {"level":"info","ts":"2026-06-10T08:39:57.943297Z","caller":"traceutil/trace.go:171","msg":"trace[2117921740] range","detail":"{range_begin:/registry/roles/openstack/keystone-openstack-keystone-fernet-rotate; range_end:; response_count:1; response_revision:7866; }","duration":"150.37159ms","start":"2026-06-10T08:39:57.792911Z","end":"2026-06-10T08:39:57.943282Z","steps":["trace[2117921740] 'range keys from in-memory index tree' (duration: 150.142234ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:57.947037Z","caller":"traceutil/trace.go:171","msg":"trace[1649318457] transaction","detail":"{read_only:false; response_revision:7867; number_of_response:1; }","duration":"128.113369ms","start":"2026-06-10T08:39:57.818873Z","end":"2026-06-10T08:39:57.946986Z","steps":["trace[1649318457] 'process raft request' (duration: 127.886114ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:58.260372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.454748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1187"} {"level":"info","ts":"2026-06-10T08:39:58.260462Z","caller":"traceutil/trace.go:171","msg":"trace[1361635529] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:7867; }","duration":"123.575741ms","start":"2026-06-10T08:39:58.136865Z","end":"2026-06-10T08:39:58.260441Z","steps":["trace[1361635529] 'range keys from in-memory index tree' (duration: 123.300285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:58.260945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.695013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/pxc-operator\" ","response":"range_response_count:1 size:1806"} {"level":"info","ts":"2026-06-10T08:39:58.261018Z","caller":"traceutil/trace.go:171","msg":"trace[1719671540] range","detail":"{range_begin:/registry/roles/openstack/pxc-operator; range_end:; response_count:1; response_revision:7867; }","duration":"142.799486ms","start":"2026-06-10T08:39:58.118205Z","end":"2026-06-10T08:39:58.261004Z","steps":["trace[1719671540] 'range keys from in-memory index tree' (duration: 141.918464ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:58.385496Z","caller":"traceutil/trace.go:171","msg":"trace[1823143723] transaction","detail":"{read_only:false; response_revision:7868; number_of_response:1; }","duration":"119.905788ms","start":"2026-06-10T08:39:58.265564Z","end":"2026-06-10T08:39:58.38547Z","steps":["trace[1823143723] 'process raft request' (duration: 119.714784ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:39:58.418427Z","caller":"traceutil/trace.go:171","msg":"trace[468085015] linearizableReadLoop","detail":"{readStateIndex:8159; appliedIndex:8158; }","duration":"151.014072ms","start":"2026-06-10T08:39:58.267395Z","end":"2026-06-10T08:39:58.418409Z","steps":["trace[468085015] 'read index received' (duration: 118.474532ms)","trace[468085015] 'applied index is now lower than readState.Index' (duration: 32.53887ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:39:58.418505Z","caller":"traceutil/trace.go:171","msg":"trace[1787466839] transaction","detail":"{read_only:false; response_revision:7869; number_of_response:1; }","duration":"152.495029ms","start":"2026-06-10T08:39:58.265968Z","end":"2026-06-10T08:39:58.418463Z","steps":["trace[1787466839] 'process raft request' (duration: 152.323285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:39:58.41877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.324009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/pxc-operator\" ","response":"range_response_count:1 size:1806"} {"level":"info","ts":"2026-06-10T08:39:58.41887Z","caller":"traceutil/trace.go:171","msg":"trace[1539093723] range","detail":"{range_begin:/registry/roles/openstack/pxc-operator; range_end:; response_count:1; response_revision:7869; }","duration":"151.474443ms","start":"2026-06-10T08:39:58.267372Z","end":"2026-06-10T08:39:58.418847Z","steps":["trace[1539093723] 'agreement among raft nodes before linearized reading' (duration: 151.123365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:01.989534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.707159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:280 size:219805"} {"level":"info","ts":"2026-06-10T08:40:01.98961Z","caller":"traceutil/trace.go:171","msg":"trace[450770588] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:280; response_revision:7884; }","duration":"100.831122ms","start":"2026-06-10T08:40:01.888762Z","end":"2026-06-10T08:40:01.989593Z","steps":["trace[450770588] 'range keys from in-memory index tree' (duration: 99.668312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:02.841399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.65385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:40:02.841467Z","caller":"traceutil/trace.go:171","msg":"trace[1344652599] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:7889; }","duration":"138.764602ms","start":"2026-06-10T08:40:02.702686Z","end":"2026-06-10T08:40:02.841451Z","steps":["trace[1344652599] 'count revisions from in-memory index tree' (duration: 138.588556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:02.84251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.013361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:280 size:219805"} {"level":"info","ts":"2026-06-10T08:40:02.842581Z","caller":"traceutil/trace.go:171","msg":"trace[1832112022] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:280; response_revision:7889; }","duration":"118.157025ms","start":"2026-06-10T08:40:02.72441Z","end":"2026-06-10T08:40:02.842567Z","steps":["trace[1832112022] 'range keys from in-memory index tree' (duration: 117.092517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:02.84287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.599217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:280 size:219805"} {"level":"info","ts":"2026-06-10T08:40:02.842949Z","caller":"traceutil/trace.go:171","msg":"trace[373037976] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:280; response_revision:7889; }","duration":"122.725849ms","start":"2026-06-10T08:40:02.720207Z","end":"2026-06-10T08:40:02.842933Z","steps":["trace[373037976] 'range keys from in-memory index tree' (duration: 121.202801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:03.774476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.058057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:280 size:219805"} {"level":"info","ts":"2026-06-10T08:40:03.774644Z","caller":"traceutil/trace.go:171","msg":"trace[2012033589] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:280; response_revision:7894; }","duration":"102.284824ms","start":"2026-06-10T08:40:03.672342Z","end":"2026-06-10T08:40:03.774627Z","steps":["trace[2012033589] 'range keys from in-memory index tree' (duration: 100.788066ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:40:05.9382Z","caller":"traceutil/trace.go:171","msg":"trace[1067047382] linearizableReadLoop","detail":"{readStateIndex:8196; appliedIndex:8195; }","duration":"106.376552ms","start":"2026-06-10T08:40:05.831797Z","end":"2026-06-10T08:40:05.938174Z","steps":["trace[1067047382] 'read index received' (duration: 106.219258ms)","trace[1067047382] 'applied index is now lower than readState.Index' (duration: 156.084µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:40:05.938415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.605507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-10T08:40:05.938338Z","caller":"traceutil/trace.go:171","msg":"trace[585076195] transaction","detail":"{read_only:false; response_revision:7904; number_of_response:1; }","duration":"140.066534ms","start":"2026-06-10T08:40:05.79824Z","end":"2026-06-10T08:40:05.938306Z","steps":["trace[585076195] 'process raft request' (duration: 139.770937ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:40:05.938465Z","caller":"traceutil/trace.go:171","msg":"trace[246655285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7904; }","duration":"106.697949ms","start":"2026-06-10T08:40:05.831754Z","end":"2026-06-10T08:40:05.938452Z","steps":["trace[246655285] 'agreement among raft nodes before linearized reading' (duration: 106.526425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:05.98005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.282515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" ","response":"range_response_count:1 size:18201"} {"level":"info","ts":"2026-06-10T08:40:05.980108Z","caller":"traceutil/trace.go:171","msg":"trace[235183589] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-pxc; range_end:; response_count:1; response_revision:7906; }","duration":"121.386697ms","start":"2026-06-10T08:40:05.858708Z","end":"2026-06-10T08:40:05.980094Z","steps":["trace[235183589] 'agreement among raft nodes before linearized reading' (duration: 121.229563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:05.980261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.857024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9580"} {"level":"info","ts":"2026-06-10T08:40:05.980301Z","caller":"traceutil/trace.go:171","msg":"trace[1611848555] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:7906; }","duration":"106.918966ms","start":"2026-06-10T08:40:05.873372Z","end":"2026-06-10T08:40:05.980291Z","steps":["trace[1611848555] 'agreement among raft nodes before linearized reading' (duration: 106.810993ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:40:06.788867Z","caller":"traceutil/trace.go:171","msg":"trace[38904611] transaction","detail":"{read_only:false; response_revision:7909; number_of_response:1; }","duration":"108.598544ms","start":"2026-06-10T08:40:06.680234Z","end":"2026-06-10T08:40:06.788833Z","steps":["trace[38904611] 'process raft request' (duration: 43.033205ms)","trace[38904611] 'compare' (duration: 65.426726ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:40:07.3278Z","caller":"traceutil/trace.go:171","msg":"trace[266809228] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"219.055441ms","start":"2026-06-10T08:40:07.108724Z","end":"2026-06-10T08:40:07.32778Z","steps":["trace[266809228] 'process raft request' (duration: 218.731242ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:40:09.52402Z","caller":"traceutil/trace.go:171","msg":"trace[974024715] transaction","detail":"{read_only:false; response_revision:7921; number_of_response:1; }","duration":"105.160751ms","start":"2026-06-10T08:40:09.418836Z","end":"2026-06-10T08:40:09.523997Z","steps":["trace[974024715] 'process raft request' (duration: 85.419849ms)","trace[974024715] 'compare' (duration: 19.26173ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:40:10.243241Z","caller":"traceutil/trace.go:171","msg":"trace[416929426] linearizableReadLoop","detail":"{readStateIndex:8220; appliedIndex:8219; }","duration":"125.032524ms","start":"2026-06-10T08:40:10.118183Z","end":"2026-06-10T08:40:10.243216Z","steps":["trace[416929426] 'read index received' (duration: 117.509286ms)","trace[416929426] 'applied index is now lower than readState.Index' (duration: 7.522137ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:40:10.243331Z","caller":"traceutil/trace.go:171","msg":"trace[1787349164] transaction","detail":"{read_only:false; response_revision:7927; number_of_response:1; }","duration":"130.745436ms","start":"2026-06-10T08:40:10.112559Z","end":"2026-06-10T08:40:10.243304Z","steps":["trace[1787349164] 'process raft request' (duration: 123.099505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:10.243423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.226538ms","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":"info","ts":"2026-06-10T08:40:10.243475Z","caller":"traceutil/trace.go:171","msg":"trace[718478901] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:7927; }","duration":"125.304551ms","start":"2026-06-10T08:40:10.118157Z","end":"2026-06-10T08:40:10.243462Z","steps":["trace[718478901] 'agreement among raft nodes before linearized reading' (duration: 125.151037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:10.243479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.241851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-n5vjd\" ","response":"range_response_count:1 size:5483"} {"level":"info","ts":"2026-06-10T08:40:10.243526Z","caller":"traceutil/trace.go:171","msg":"trace[277936373] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-n5vjd; range_end:; response_count:1; response_revision:7927; }","duration":"103.330744ms","start":"2026-06-10T08:40:10.140183Z","end":"2026-06-10T08:40:10.243513Z","steps":["trace[277936373] 'agreement among raft nodes before linearized reading' (duration: 103.17406ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:40:11.651644Z","caller":"traceutil/trace.go:171","msg":"trace[1975349228] transaction","detail":"{read_only:false; response_revision:7932; number_of_response:1; }","duration":"116.751325ms","start":"2026-06-10T08:40:11.534853Z","end":"2026-06-10T08:40:11.651604Z","steps":["trace[1975349228] 'process raft request' (duration: 116.481578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:13.661912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.936317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wkffj\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-06-10T08:40:13.662006Z","caller":"traceutil/trace.go:171","msg":"trace[1673439203] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wkffj; range_end:; response_count:1; response_revision:7942; }","duration":"103.07195ms","start":"2026-06-10T08:40:13.558908Z","end":"2026-06-10T08:40:13.66198Z","steps":["trace[1673439203] 'range keys from in-memory index tree' (duration: 102.689471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:40:14.525951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.750269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-zl8rx\" ","response":"range_response_count:1 size:4904"} {"level":"info","ts":"2026-06-10T08:40:14.526016Z","caller":"traceutil/trace.go:171","msg":"trace[1338457528] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-zl8rx; range_end:; response_count:1; response_revision:7948; }","duration":"102.860043ms","start":"2026-06-10T08:40:14.423143Z","end":"2026-06-10T08:40:14.526003Z","steps":["trace[1338457528] 'range keys from in-memory index tree' (duration: 102.592576ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:40:14.879864Z","caller":"traceutil/trace.go:171","msg":"trace[111386546] transaction","detail":"{read_only:false; response_revision:7949; number_of_response:1; }","duration":"100.31242ms","start":"2026-06-10T08:40:14.779533Z","end":"2026-06-10T08:40:14.879846Z","steps":["trace[111386546] 'process raft request' (duration: 100.184887ms)"],"step_count":1}