{"level":"warn","ts":"2026-03-18T05:34:58.235472Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T05:34:58.235588Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-03-18T05:34:58.235654Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T05:34:58.235668Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-03-18T05:34:58.235686Z","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-03-18T05:34:58.236344Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-03-18T05:34:58.23642Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-03-18T05:34:58.240594Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.985017ms"} {"level":"info","ts":"2026-03-18T05:34:58.271903Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-03-18T05:34:58.272014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T05:34:58.272058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-03-18T05:34:58.27208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T05:34:58.272095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-03-18T05:34:58.27215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-03-18T05:34:58.285795Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T05:34:58.288031Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T05:34:58.289528Z","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-03-18T05:34:58.29181Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T05:34:58.291983Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T05:34:58.292076Z","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-03-18T05:34:58.292175Z","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-03-18T05:34:58.292188Z","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-03-18T05:34:58.294753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-03-18T05:34:58.295224Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-03-18T05:34:58.297478Z","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-03-18T05:34:58.297566Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-03-18T05:34:58.297597Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-03-18T05:34:58.297837Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T05:34:58.297895Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T05:34:58.375166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T05:34:58.375262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T05:34:58.375325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-03-18T05:34:58.375366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-03-18T05:34:58.375407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-03-18T05:34:58.375445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-03-18T05:34:58.375482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-03-18T05:34:58.376517Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:58.377257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:34:58.37725Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T05:34:58.37731Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T05:34:58.377635Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T05:34:58.377721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T05:34:58.377773Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:58.37791Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:58.377987Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T05:34:58.381033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-03-18T05:34:58.38106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T05:35:01.661015Z","caller":"traceutil/trace.go:171","msg":"trace[1741932716] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"205.520288ms","start":"2026-03-18T05:35:01.455462Z","end":"2026-03-18T05:35:01.660983Z","steps":["trace[1741932716] 'process raft request' (duration: 202.182857ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:15.631877Z","caller":"traceutil/trace.go:171","msg":"trace[1972945110] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"158.11137ms","start":"2026-03-18T05:35:15.473707Z","end":"2026-03-18T05:35:15.631819Z","steps":["trace[1972945110] 'process raft request' (duration: 157.70036ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:32.668157Z","caller":"traceutil/trace.go:171","msg":"trace[1386338997] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"101.554056ms","start":"2026-03-18T05:35:32.566572Z","end":"2026-03-18T05:35:32.668126Z","steps":["trace[1386338997] 'process raft request' (duration: 101.391732ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:36.032833Z","caller":"traceutil/trace.go:171","msg":"trace[1670745562] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"163.575443ms","start":"2026-03-18T05:35:35.869212Z","end":"2026-03-18T05:35:36.032788Z","steps":["trace[1670745562] 'process raft request' (duration: 163.270846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:37.325778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.397668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:37.325942Z","caller":"traceutil/trace.go:171","msg":"trace[256327975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"206.651304ms","start":"2026-03-18T05:35:37.119266Z","end":"2026-03-18T05:35:37.325917Z","steps":["trace[256327975] 'range keys from in-memory index tree' (duration: 206.213984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:37.326225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.265248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:35:37.327688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.404868ms","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-03-18T05:35:37.328271Z","caller":"traceutil/trace.go:171","msg":"trace[1974850351] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:557; }","duration":"289.297617ms","start":"2026-03-18T05:35:37.038873Z","end":"2026-03-18T05:35:37.32817Z","steps":["trace[1974850351] 'range keys from in-memory index tree' (duration: 287.125795ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:37.328422Z","caller":"traceutil/trace.go:171","msg":"trace[1487322012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"645.57197ms","start":"2026-03-18T05:35:36.682724Z","end":"2026-03-18T05:35:37.328296Z","steps":["trace[1487322012] 'range keys from in-memory index tree' (duration: 643.134211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:37.328722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:36.682694Z","time spent":"645.787106ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T05:35:39.29248Z","caller":"traceutil/trace.go:171","msg":"trace[1729790484] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:696; }","duration":"173.305005ms","start":"2026-03-18T05:35:39.119143Z","end":"2026-03-18T05:35:39.292448Z","steps":["trace[1729790484] 'read index received' (duration: 148.981432ms)","trace[1729790484] 'applied index is now lower than readState.Index' (duration: 24.322653ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:39.292646Z","caller":"traceutil/trace.go:171","msg":"trace[477904503] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"226.46392ms","start":"2026-03-18T05:35:39.066142Z","end":"2026-03-18T05:35:39.292606Z","steps":["trace[477904503] 'process raft request' (duration: 202.312841ms)","trace[477904503] 'compare' (duration: 23.701808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:35:39.292693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.51472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:35:39.292755Z","caller":"traceutil/trace.go:171","msg":"trace[1218951418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"173.647254ms","start":"2026-03-18T05:35:39.119088Z","end":"2026-03-18T05:35:39.292735Z","steps":["trace[1218951418] 'agreement among raft nodes before linearized reading' (duration: 173.520831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:42.261184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.815438ms","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-03-18T05:35:42.261269Z","caller":"traceutil/trace.go:171","msg":"trace[1634828165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:744; }","duration":"100.951561ms","start":"2026-03-18T05:35:42.1603Z","end":"2026-03-18T05:35:42.261251Z","steps":["trace[1634828165] 'range keys from in-memory index tree' (duration: 100.678575ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:49.26619Z","caller":"traceutil/trace.go:171","msg":"trace[1011882466] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"119.398633ms","start":"2026-03-18T05:35:49.146765Z","end":"2026-03-18T05:35:49.266164Z","steps":["trace[1011882466] 'process raft request' (duration: 89.612169ms)","trace[1011882466] 'compare' (duration: 29.525978ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:58.665178Z","caller":"traceutil/trace.go:171","msg":"trace[993055206] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"117.309473ms","start":"2026-03-18T05:35:58.547839Z","end":"2026-03-18T05:35:58.665149Z","steps":["trace[993055206] 'read index received' (duration: 117.088908ms)","trace[993055206] 'applied index is now lower than readState.Index' (duration: 217.305µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:35:58.665279Z","caller":"traceutil/trace.go:171","msg":"trace[1510297604] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"301.598962ms","start":"2026-03-18T05:35:58.363616Z","end":"2026-03-18T05:35:58.665215Z","steps":["trace[1510297604] 'process raft request' (duration: 301.363387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:58.665482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.373147ms","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-03-18T05:35:58.665536Z","caller":"traceutil/trace.go:171","msg":"trace[1973324713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"100.48289ms","start":"2026-03-18T05:35:58.56504Z","end":"2026-03-18T05:35:58.665523Z","steps":["trace[1973324713] 'agreement among raft nodes before linearized reading' (duration: 100.279015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:58.665614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.731414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T05:35:58.665733Z","caller":"traceutil/trace.go:171","msg":"trace[1062171365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"117.927478ms","start":"2026-03-18T05:35:58.547782Z","end":"2026-03-18T05:35:58.66571Z","steps":["trace[1062171365] 'agreement among raft nodes before linearized reading' (duration: 117.519229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:35:58.666074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:35:58.363594Z","time spent":"301.806288ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:35:58.804069Z","caller":"traceutil/trace.go:171","msg":"trace[797400185] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"133.660065ms","start":"2026-03-18T05:35:58.670381Z","end":"2026-03-18T05:35:58.804041Z","steps":["trace[797400185] 'process raft request' (duration: 133.603233ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:35:58.804144Z","caller":"traceutil/trace.go:171","msg":"trace[1560068838] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"134.065004ms","start":"2026-03-18T05:35:58.670031Z","end":"2026-03-18T05:35:58.804096Z","steps":["trace[1560068838] 'process raft request' (duration: 130.418977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.416207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.756673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:36:47.41633Z","caller":"traceutil/trace.go:171","msg":"trace[1757724044] linearizableReadLoop","detail":"{readStateIndex:1647; appliedIndex:1646; }","duration":"338.170876ms","start":"2026-03-18T05:36:47.078141Z","end":"2026-03-18T05:36:47.416312Z","steps":["trace[1757724044] 'read index received' (duration: 35.271µs)","trace[1757724044] 'applied index is now lower than readState.Index' (duration: 338.134355ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:36:47.416427Z","caller":"traceutil/trace.go:171","msg":"trace[915489404] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"494.799463ms","start":"2026-03-18T05:36:46.921613Z","end":"2026-03-18T05:36:47.416413Z","steps":["trace[915489404] 'process raft request' (duration: 133.575119ms)","trace[915489404] 'compare' (duration: 360.63444ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:36:47.416487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:46.921599Z","time spent":"494.865986ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:36:47.416871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.73216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T05:36:47.416899Z","caller":"traceutil/trace.go:171","msg":"trace[186801271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1590; }","duration":"338.804952ms","start":"2026-03-18T05:36:47.078087Z","end":"2026-03-18T05:36:47.416892Z","steps":["trace[186801271] 'agreement among raft nodes before linearized reading' (duration: 338.681419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.416922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.078064Z","time spent":"338.853743ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:36:47.416885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.621708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-18T05:36:47.416911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.71172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-737b8ece-8435-478d-82f7-82fd0561e334\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-03-18T05:36:47.41691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.868617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T05:36:47.41699Z","caller":"traceutil/trace.go:171","msg":"trace[1174219301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1590; }","duration":"338.803733ms","start":"2026-03-18T05:36:47.078162Z","end":"2026-03-18T05:36:47.416966Z","steps":["trace[1174219301] 'agreement among raft nodes before linearized reading' (duration: 338.496424ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:47.417001Z","caller":"traceutil/trace.go:171","msg":"trace[1363595261] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1590; }","duration":"298.98885ms","start":"2026-03-18T05:36:47.117993Z","end":"2026-03-18T05:36:47.416982Z","steps":["trace[1363595261] 'agreement among raft nodes before linearized reading' (duration: 298.781685ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:36:47.416995Z","caller":"traceutil/trace.go:171","msg":"trace[1749150330] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-737b8ece-8435-478d-82f7-82fd0561e334; range_end:; response_count:1; response_revision:1590; }","duration":"130.805623ms","start":"2026-03-18T05:36:47.286167Z","end":"2026-03-18T05:36:47.416972Z","steps":["trace[1749150330] 'agreement among raft nodes before linearized reading' (duration: 130.599348ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:36:47.417034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:36:47.078125Z","time spent":"338.899545ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T05:37:00.832441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.52259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T05:37:00.832584Z","caller":"traceutil/trace.go:171","msg":"trace[475694778] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1697; }","duration":"277.721556ms","start":"2026-03-18T05:37:00.554831Z","end":"2026-03-18T05:37:00.832553Z","steps":["trace[475694778] 'range keys from in-memory index tree' (duration: 277.301045ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:00.833439Z","caller":"traceutil/trace.go:171","msg":"trace[1212036711] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"214.892626ms","start":"2026-03-18T05:37:00.618514Z","end":"2026-03-18T05:37:00.833406Z","steps":["trace[1212036711] 'process raft request' (duration: 212.293603ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:32.606493Z","caller":"traceutil/trace.go:171","msg":"trace[518012042] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"109.382995ms","start":"2026-03-18T05:37:32.497069Z","end":"2026-03-18T05:37:32.606452Z","steps":["trace[518012042] 'process raft request' (duration: 109.105818ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:38.310254Z","caller":"traceutil/trace.go:171","msg":"trace[1184643899] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"133.924967ms","start":"2026-03-18T05:37:38.176301Z","end":"2026-03-18T05:37:38.310226Z","steps":["trace[1184643899] 'read index received' (duration: 133.717882ms)","trace[1184643899] 'applied index is now lower than readState.Index' (duration: 206.155µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:37:38.310373Z","caller":"traceutil/trace.go:171","msg":"trace[1370122791] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"143.330085ms","start":"2026-03-18T05:37:38.166995Z","end":"2026-03-18T05:37:38.310325Z","steps":["trace[1370122791] 'process raft request' (duration: 143.078669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:38.310469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.127372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:37:38.310558Z","caller":"traceutil/trace.go:171","msg":"trace[179219953] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1899; }","duration":"134.264206ms","start":"2026-03-18T05:37:38.176273Z","end":"2026-03-18T05:37:38.310537Z","steps":["trace[179219953] 'agreement among raft nodes before linearized reading' (duration: 134.073301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:39.133401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.863776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:37:39.133559Z","caller":"traceutil/trace.go:171","msg":"trace[1629397136] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"442.665603ms","start":"2026-03-18T05:37:38.690864Z","end":"2026-03-18T05:37:39.13353Z","steps":["trace[1629397136] 'process raft request' (duration: 191.539641ms)","trace[1629397136] 'compare' (duration: 250.713752ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:37:39.133649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:38.690835Z","time spent":"442.768456ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:37:39.156994Z","caller":"traceutil/trace.go:171","msg":"trace[1052684720] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"183.940478ms","start":"2026-03-18T05:37:38.973013Z","end":"2026-03-18T05:37:39.156954Z","steps":["trace[1052684720] 'process raft request' (duration: 183.732173ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:37:39.450879Z","caller":"traceutil/trace.go:171","msg":"trace[2051811799] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1971; }","duration":"334.845153ms","start":"2026-03-18T05:37:39.115998Z","end":"2026-03-18T05:37:39.450843Z","steps":["trace[2051811799] 'read index received' (duration: 40.710159ms)","trace[2051811799] 'applied index is now lower than readState.Index' (duration: 294.133714ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:37:39.450911Z","caller":"traceutil/trace.go:171","msg":"trace[741039359] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"476.750891ms","start":"2026-03-18T05:37:38.974112Z","end":"2026-03-18T05:37:39.450863Z","steps":["trace[741039359] 'process raft request' (duration: 476.558226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:39.451096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.071399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:37:39.451142Z","caller":"traceutil/trace.go:171","msg":"trace[147802724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1903; }","duration":"335.170411ms","start":"2026-03-18T05:37:39.11596Z","end":"2026-03-18T05:37:39.45113Z","steps":["trace[147802724] 'agreement among raft nodes before linearized reading' (duration: 335.055008ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:39.451182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:39.115942Z","time spent":"335.232152ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T05:37:39.451255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.50909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-18T05:37:39.451314Z","caller":"traceutil/trace.go:171","msg":"trace[2078493170] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1903; }","duration":"323.613892ms","start":"2026-03-18T05:37:39.127684Z","end":"2026-03-18T05:37:39.451298Z","steps":["trace[2078493170] 'agreement among raft nodes before linearized reading' (duration: 323.406727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:37:39.451354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:39.127661Z","time spent":"323.685094ms","remote":"127.0.0.1:33774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-18T05:37:39.451441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:37:38.974098Z","time spent":"477.024898ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:38:29.84513Z","caller":"traceutil/trace.go:171","msg":"trace[2135775794] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"202.178185ms","start":"2026-03-18T05:38:29.642924Z","end":"2026-03-18T05:38:29.845102Z","steps":["trace[2135775794] 'process raft request' (duration: 143.67008ms)","trace[2135775794] 'compare' (duration: 58.066795ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:38:29.845245Z","caller":"traceutil/trace.go:171","msg":"trace[1777182166] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"131.566679ms","start":"2026-03-18T05:38:29.713651Z","end":"2026-03-18T05:38:29.845218Z","steps":["trace[1777182166] 'process raft request' (duration: 131.521248ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:38:29.845515Z","caller":"traceutil/trace.go:171","msg":"trace[912336851] transaction","detail":"{read_only:false; response_revision:2300; number_of_response:1; }","duration":"202.38408ms","start":"2026-03-18T05:38:29.643117Z","end":"2026-03-18T05:38:29.845501Z","steps":["trace[912336851] 'process raft request' (duration: 201.877988ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:38:29.845692Z","caller":"traceutil/trace.go:171","msg":"trace[1949345864] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"174.727155ms","start":"2026-03-18T05:38:29.670953Z","end":"2026-03-18T05:38:29.845681Z","steps":["trace[1949345864] 'process raft request' (duration: 174.137031ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:38:29.845758Z","caller":"traceutil/trace.go:171","msg":"trace[786868600] linearizableReadLoop","detail":"{readStateIndex:2388; appliedIndex:2385; }","duration":"132.081361ms","start":"2026-03-18T05:38:29.713567Z","end":"2026-03-18T05:38:29.845648Z","steps":["trace[786868600] 'read index received' (duration: 73.049054ms)","trace[786868600] 'applied index is now lower than readState.Index' (duration: 59.019357ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:38:29.846216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.626904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129702"} {"level":"info","ts":"2026-03-18T05:38:29.846469Z","caller":"traceutil/trace.go:171","msg":"trace[406921496] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2302; }","duration":"132.929913ms","start":"2026-03-18T05:38:29.713524Z","end":"2026-03-18T05:38:29.846454Z","steps":["trace[406921496] 'agreement among raft nodes before linearized reading' (duration: 132.484161ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:38:29.958183Z","caller":"traceutil/trace.go:171","msg":"trace[218377164] linearizableReadLoop","detail":"{readStateIndex:2391; appliedIndex:2390; }","duration":"107.258005ms","start":"2026-03-18T05:38:29.850901Z","end":"2026-03-18T05:38:29.958159Z","steps":["trace[218377164] 'read index received' (duration: 16.620869ms)","trace[218377164] 'applied index is now lower than readState.Index' (duration: 90.635846ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:38:29.958215Z","caller":"traceutil/trace.go:171","msg":"trace[408400631] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"108.021414ms","start":"2026-03-18T05:38:29.850156Z","end":"2026-03-18T05:38:29.958177Z","steps":["trace[408400631] 'process raft request' (duration: 104.753185ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:38:29.958263Z","caller":"traceutil/trace.go:171","msg":"trace[1533205165] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"106.379234ms","start":"2026-03-18T05:38:29.851849Z","end":"2026-03-18T05:38:29.958229Z","steps":["trace[1533205165] 'process raft request' (duration: 106.139308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:38:29.95866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.740368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-03-18T05:38:29.958782Z","caller":"traceutil/trace.go:171","msg":"trace[1140057657] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2305; }","duration":"107.874751ms","start":"2026-03-18T05:38:29.850878Z","end":"2026-03-18T05:38:29.958753Z","steps":["trace[1140057657] 'agreement among raft nodes before linearized reading' (duration: 107.377049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:38:33.327856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.836121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:38:33.32798Z","caller":"traceutil/trace.go:171","msg":"trace[1633071752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2339; }","duration":"208.022495ms","start":"2026-03-18T05:38:33.11993Z","end":"2026-03-18T05:38:33.327952Z","steps":["trace[1633071752] 'range keys from in-memory index tree' (duration: 207.652146ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:39:07.566098Z","caller":"traceutil/trace.go:171","msg":"trace[1741647108] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"279.335877ms","start":"2026-03-18T05:39:07.286682Z","end":"2026-03-18T05:39:07.566018Z","steps":["trace[1741647108] 'process raft request' (duration: 278.309442ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:39:07.579658Z","caller":"traceutil/trace.go:171","msg":"trace[154886479] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"117.123117ms","start":"2026-03-18T05:39:07.462502Z","end":"2026-03-18T05:39:07.579625Z","steps":["trace[154886479] 'process raft request' (duration: 116.797369ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:39:08.130521Z","caller":"traceutil/trace.go:171","msg":"trace[1959113171] linearizableReadLoop","detail":"{readStateIndex:2628; appliedIndex:2627; }","duration":"111.650036ms","start":"2026-03-18T05:39:08.018826Z","end":"2026-03-18T05:39:08.130476Z","steps":["trace[1959113171] 'read index received' (duration: 77.540995ms)","trace[1959113171] 'applied index is now lower than readState.Index' (duration: 34.10707ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:39:08.130644Z","caller":"traceutil/trace.go:171","msg":"trace[173021651] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"138.512552ms","start":"2026-03-18T05:39:07.992055Z","end":"2026-03-18T05:39:08.130568Z","steps":["trace[173021651] 'process raft request' (duration: 104.450353ms)","trace[173021651] 'compare' (duration: 33.650269ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:39:08.130816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.969303ms","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-03-18T05:39:08.130868Z","caller":"traceutil/trace.go:171","msg":"trace[557742620] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2533; }","duration":"112.093966ms","start":"2026-03-18T05:39:08.018762Z","end":"2026-03-18T05:39:08.130855Z","steps":["trace[557742620] 'agreement among raft nodes before linearized reading' (duration: 111.86654ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:39:08.612763Z","caller":"traceutil/trace.go:171","msg":"trace[1580720346] transaction","detail":"{read_only:false; response_revision:2534; number_of_response:1; }","duration":"419.966889ms","start":"2026-03-18T05:39:08.19277Z","end":"2026-03-18T05:39:08.612737Z","steps":["trace[1580720346] 'process raft request' (duration: 417.369817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:39:08.612923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:39:08.192747Z","time spent":"420.087572ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:39:08.61298Z","caller":"traceutil/trace.go:171","msg":"trace[909169687] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"166.126975ms","start":"2026-03-18T05:39:08.446708Z","end":"2026-03-18T05:39:08.612835Z","steps":["trace[909169687] 'process raft request' (duration: 166.030833ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:39:08.612907Z","caller":"traceutil/trace.go:171","msg":"trace[1829308141] linearizableReadLoop","detail":"{readStateIndex:2629; appliedIndex:2628; }","duration":"419.706233ms","start":"2026-03-18T05:39:08.193162Z","end":"2026-03-18T05:39:08.612868Z","steps":["trace[1829308141] 'read index received' (duration: 416.991807ms)","trace[1829308141] 'applied index is now lower than readState.Index' (duration: 2.712956ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:39:08.613159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.98077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-18T05:39:08.613209Z","caller":"traceutil/trace.go:171","msg":"trace[708440888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2535; }","duration":"420.089692ms","start":"2026-03-18T05:39:08.193106Z","end":"2026-03-18T05:39:08.613195Z","steps":["trace[708440888] 'agreement among raft nodes before linearized reading' (duration: 419.912448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:39:08.613259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:39:08.193082Z","time spent":"420.167994ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:39:08.613295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.728426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-18T05:39:08.613342Z","caller":"traceutil/trace.go:171","msg":"trace[1708917914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2535; }","duration":"415.792249ms","start":"2026-03-18T05:39:08.197536Z","end":"2026-03-18T05:39:08.613329Z","steps":["trace[1708917914] 'agreement among raft nodes before linearized reading' (duration: 415.645395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:39:08.613379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:39:08.197524Z","time spent":"415.84686ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T05:39:08.613391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.904868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.189dd8ea2516e7b8\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-03-18T05:39:08.613446Z","caller":"traceutil/trace.go:171","msg":"trace[1035373155] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.189dd8ea2516e7b8; range_end:; response_count:1; response_revision:2535; }","duration":"205.00244ms","start":"2026-03-18T05:39:08.408427Z","end":"2026-03-18T05:39:08.61343Z","steps":["trace[1035373155] 'agreement among raft nodes before linearized reading' (duration: 204.856736ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:41:34.328341Z","caller":"traceutil/trace.go:171","msg":"trace[1368596621] transaction","detail":"{read_only:false; response_revision:3433; number_of_response:1; }","duration":"153.735923ms","start":"2026-03-18T05:41:34.174567Z","end":"2026-03-18T05:41:34.328303Z","steps":["trace[1368596621] 'process raft request' (duration: 153.504117ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:41:39.885746Z","caller":"traceutil/trace.go:171","msg":"trace[1936723727] transaction","detail":"{read_only:false; response_revision:3455; number_of_response:1; }","duration":"414.148852ms","start":"2026-03-18T05:41:39.471483Z","end":"2026-03-18T05:41:39.885632Z","steps":["trace[1936723727] 'process raft request' (duration: 413.953147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:41:39.885956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:41:39.471462Z","time spent":"414.380837ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:41:39.886487Z","caller":"traceutil/trace.go:171","msg":"trace[1099320728] transaction","detail":"{read_only:false; response_revision:3457; number_of_response:1; }","duration":"411.667404ms","start":"2026-03-18T05:41:39.474775Z","end":"2026-03-18T05:41:39.886442Z","steps":["trace[1099320728] 'process raft request' (duration: 411.573161ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:41:39.886523Z","caller":"traceutil/trace.go:171","msg":"trace[905304461] transaction","detail":"{read_only:false; response_revision:3456; number_of_response:1; }","duration":"414.743136ms","start":"2026-03-18T05:41:39.471766Z","end":"2026-03-18T05:41:39.886509Z","steps":["trace[905304461] 'process raft request' (duration: 414.401108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:41:39.886658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:41:39.471751Z","time spent":"414.866899ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:41:39.886729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:41:39.474764Z","time spent":"411.839717ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:41:39.886785Z","caller":"traceutil/trace.go:171","msg":"trace[1785880261] transaction","detail":"{read_only:false; response_revision:3458; number_of_response:1; }","duration":"349.771138ms","start":"2026-03-18T05:41:39.536969Z","end":"2026-03-18T05:41:39.88674Z","steps":["trace[1785880261] 'process raft request' (duration: 349.420409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:41:39.887048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:41:39.536928Z","time spent":"349.981313ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:41:39.887165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.662391ms","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-03-18T05:41:39.887247Z","caller":"traceutil/trace.go:171","msg":"trace[1590522734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3458; }","duration":"386.773524ms","start":"2026-03-18T05:41:39.500451Z","end":"2026-03-18T05:41:39.887224Z","steps":["trace[1590522734] 'agreement among raft nodes before linearized reading' (duration: 386.488087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:41:39.887292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:41:39.500437Z","time spent":"386.847205ms","remote":"127.0.0.1:41138","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-03-18T05:41:39.887296Z","caller":"traceutil/trace.go:171","msg":"trace[1150836003] linearizableReadLoop","detail":"{readStateIndex:3590; appliedIndex:3587; }","duration":"386.080688ms","start":"2026-03-18T05:41:39.500484Z","end":"2026-03-18T05:41:39.886565Z","steps":["trace[1150836003] 'read index received' (duration: 384.911269ms)","trace[1150836003] 'applied index is now lower than readState.Index' (duration: 1.167959ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:43:06.561168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.202996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:43:06.561379Z","caller":"traceutil/trace.go:171","msg":"trace[375683619] linearizableReadLoop","detail":"{readStateIndex:4676; appliedIndex:4674; }","duration":"136.212151ms","start":"2026-03-18T05:43:06.425152Z","end":"2026-03-18T05:43:06.561364Z","steps":["trace[375683619] 'read index received' (duration: 23.686958ms)","trace[375683619] 'applied index is now lower than readState.Index' (duration: 112.524333ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:06.561382Z","caller":"traceutil/trace.go:171","msg":"trace[1124182667] transaction","detail":"{read_only:false; response_revision:4498; number_of_response:1; }","duration":"228.143451ms","start":"2026-03-18T05:43:06.333215Z","end":"2026-03-18T05:43:06.561358Z","steps":["trace[1124182667] 'process raft request' (duration: 115.653629ms)","trace[1124182667] 'compare' (duration: 111.97827ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:43:06.561717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.549449ms","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-03-18T05:43:06.561816Z","caller":"traceutil/trace.go:171","msg":"trace[1918297875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4499; }","duration":"136.672601ms","start":"2026-03-18T05:43:06.42512Z","end":"2026-03-18T05:43:06.561793Z","steps":["trace[1918297875] 'agreement among raft nodes before linearized reading' (duration: 136.411635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:06.561843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.780947ms","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-03-18T05:43:06.561847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.718216ms","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-03-18T05:43:06.561896Z","caller":"traceutil/trace.go:171","msg":"trace[690700681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4499; }","duration":"104.836748ms","start":"2026-03-18T05:43:06.457048Z","end":"2026-03-18T05:43:06.561884Z","steps":["trace[690700681] 'agreement among raft nodes before linearized reading' (duration: 104.701665ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:06.561902Z","caller":"traceutil/trace.go:171","msg":"trace[95676461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4499; }","duration":"104.827878ms","start":"2026-03-18T05:43:06.457063Z","end":"2026-03-18T05:43:06.561891Z","steps":["trace[95676461] 'agreement among raft nodes before linearized reading' (duration: 104.668525ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:06.561713Z","caller":"traceutil/trace.go:171","msg":"trace[1758311648] transaction","detail":"{read_only:false; response_revision:4499; number_of_response:1; }","duration":"162.223323ms","start":"2026-03-18T05:43:06.399442Z","end":"2026-03-18T05:43:06.561666Z","steps":["trace[1758311648] 'process raft request' (duration: 161.844454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:06.561917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.730085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:43:06.562051Z","caller":"traceutil/trace.go:171","msg":"trace[381783758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4499; }","duration":"129.946239ms","start":"2026-03-18T05:43:06.432077Z","end":"2026-03-18T05:43:06.562024Z","steps":["trace[381783758] 'agreement among raft nodes before linearized reading' (duration: 129.54818ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:08.85229Z","caller":"traceutil/trace.go:171","msg":"trace[1682322455] linearizableReadLoop","detail":"{readStateIndex:4798; appliedIndex:4797; }","duration":"263.902127ms","start":"2026-03-18T05:43:08.588373Z","end":"2026-03-18T05:43:08.852275Z","steps":["trace[1682322455] 'read index received' (duration: 263.690432ms)","trace[1682322455] 'applied index is now lower than readState.Index' (duration: 211.205µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:08.852377Z","caller":"traceutil/trace.go:171","msg":"trace[1318372365] transaction","detail":"{read_only:false; response_revision:4618; number_of_response:1; }","duration":"284.189743ms","start":"2026-03-18T05:43:08.568179Z","end":"2026-03-18T05:43:08.852368Z","steps":["trace[1318372365] 'process raft request' (duration: 283.945117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:08.852551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.156124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:43:08.85261Z","caller":"traceutil/trace.go:171","msg":"trace[762351716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4618; }","duration":"264.264355ms","start":"2026-03-18T05:43:08.588332Z","end":"2026-03-18T05:43:08.852597Z","steps":["trace[762351716] 'agreement among raft nodes before linearized reading' (duration: 264.063541ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:09.071339Z","caller":"traceutil/trace.go:171","msg":"trace[1975498691] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"273.734852ms","start":"2026-03-18T05:43:08.797513Z","end":"2026-03-18T05:43:09.071248Z","steps":["trace[1975498691] 'process raft request' (duration: 270.165456ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:09.071413Z","caller":"traceutil/trace.go:171","msg":"trace[1013715916] linearizableReadLoop","detail":"{readStateIndex:4799; appliedIndex:4798; }","duration":"218.95821ms","start":"2026-03-18T05:43:08.852351Z","end":"2026-03-18T05:43:09.071309Z","steps":["trace[1013715916] 'read index received' (duration: 215.444296ms)","trace[1013715916] 'applied index is now lower than readState.Index' (duration: 3.493293ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:09.072257Z","caller":"traceutil/trace.go:171","msg":"trace[1179706769] transaction","detail":"{read_only:false; response_revision:4620; number_of_response:1; }","duration":"215.059227ms","start":"2026-03-18T05:43:08.85716Z","end":"2026-03-18T05:43:09.072219Z","steps":["trace[1179706769] 'process raft request' (duration: 214.040973ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:09.072254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.928631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T05:43:09.072676Z","caller":"traceutil/trace.go:171","msg":"trace[1015020302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4620; }","duration":"476.386422ms","start":"2026-03-18T05:43:08.596259Z","end":"2026-03-18T05:43:09.072646Z","steps":["trace[1015020302] 'agreement among raft nodes before linearized reading' (duration: 475.118413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:09.072677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.861557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-18T05:43:09.072765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.926635ms","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-03-18T05:43:09.072813Z","caller":"traceutil/trace.go:171","msg":"trace[108725717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4620; }","duration":"478.029551ms","start":"2026-03-18T05:43:08.594755Z","end":"2026-03-18T05:43:09.072785Z","steps":["trace[108725717] 'agreement among raft nodes before linearized reading' (duration: 476.846403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:09.072809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:08.596249Z","time spent":"476.509645ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T05:43:09.072872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:08.594744Z","time spent":"478.116803ms","remote":"127.0.0.1:41138","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-03-18T05:43:09.072883Z","caller":"traceutil/trace.go:171","msg":"trace[266275980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4620; }","duration":"477.110269ms","start":"2026-03-18T05:43:08.595742Z","end":"2026-03-18T05:43:09.072853Z","steps":["trace[266275980] 'agreement among raft nodes before linearized reading' (duration: 475.674345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:09.072948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:08.595709Z","time spent":"477.220952ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:43:09.073154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.149335ms","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-03-18T05:43:09.073212Z","caller":"traceutil/trace.go:171","msg":"trace[14535699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4621; }","duration":"153.255057ms","start":"2026-03-18T05:43:08.919941Z","end":"2026-03-18T05:43:09.073196Z","steps":["trace[14535699] 'agreement among raft nodes before linearized reading' (duration: 152.795827ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:09.196244Z","caller":"traceutil/trace.go:171","msg":"trace[184731418] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"112.770279ms","start":"2026-03-18T05:43:09.083446Z","end":"2026-03-18T05:43:09.196216Z","steps":["trace[184731418] 'process raft request' (duration: 112.558504ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:09.196641Z","caller":"traceutil/trace.go:171","msg":"trace[491458597] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"112.664916ms","start":"2026-03-18T05:43:09.083949Z","end":"2026-03-18T05:43:09.196614Z","steps":["trace[491458597] 'process raft request' (duration: 112.488332ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:09.196684Z","caller":"traceutil/trace.go:171","msg":"trace[448987280] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"112.813001ms","start":"2026-03-18T05:43:09.083846Z","end":"2026-03-18T05:43:09.196659Z","steps":["trace[448987280] 'process raft request' (duration: 112.318579ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:13.037583Z","caller":"traceutil/trace.go:171","msg":"trace[1305967027] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"148.691629ms","start":"2026-03-18T05:43:12.888856Z","end":"2026-03-18T05:43:13.037548Z","steps":["trace[1305967027] 'process raft request' (duration: 90.306772ms)","trace[1305967027] 'compare' (duration: 57.852744ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:13.037713Z","caller":"traceutil/trace.go:171","msg":"trace[1705054183] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"133.129487ms","start":"2026-03-18T05:43:12.904558Z","end":"2026-03-18T05:43:13.037688Z","steps":["trace[1705054183] 'process raft request' (duration: 132.904411ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:13.235651Z","caller":"traceutil/trace.go:171","msg":"trace[1885740284] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"150.964863ms","start":"2026-03-18T05:43:13.084657Z","end":"2026-03-18T05:43:13.235622Z","steps":["trace[1885740284] 'process raft request' (duration: 145.910962ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:13.240835Z","caller":"traceutil/trace.go:171","msg":"trace[1441591417] linearizableReadLoop","detail":"{readStateIndex:4889; appliedIndex:4886; }","duration":"149.364635ms","start":"2026-03-18T05:43:13.091432Z","end":"2026-03-18T05:43:13.240796Z","steps":["trace[1441591417] 'read index received' (duration: 139.1529ms)","trace[1441591417] 'applied index is now lower than readState.Index' (duration: 10.210005ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:13.240862Z","caller":"traceutil/trace.go:171","msg":"trace[1741231713] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"150.869341ms","start":"2026-03-18T05:43:13.089955Z","end":"2026-03-18T05:43:13.240825Z","steps":["trace[1741231713] 'process raft request' (duration: 150.561184ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:13.240876Z","caller":"traceutil/trace.go:171","msg":"trace[1317133950] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"149.450597ms","start":"2026-03-18T05:43:13.091397Z","end":"2026-03-18T05:43:13.240848Z","steps":["trace[1317133950] 'process raft request' (duration: 149.340484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:13.24112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.662001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T05:43:13.241738Z","caller":"traceutil/trace.go:171","msg":"trace[1112629367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4705; }","duration":"150.330297ms","start":"2026-03-18T05:43:13.091394Z","end":"2026-03-18T05:43:13.241724Z","steps":["trace[1112629367] 'agreement among raft nodes before linearized reading' (duration: 149.555549ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:20.077439Z","caller":"traceutil/trace.go:171","msg":"trace[351608403] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"101.707335ms","start":"2026-03-18T05:43:19.975693Z","end":"2026-03-18T05:43:20.0774Z","steps":["trace[351608403] 'process raft request' (duration: 93.913118ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:21.541708Z","caller":"traceutil/trace.go:171","msg":"trace[4831834] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"236.786377ms","start":"2026-03-18T05:43:21.304897Z","end":"2026-03-18T05:43:21.541683Z","steps":["trace[4831834] 'process raft request' (duration: 236.560841ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:21.542885Z","caller":"traceutil/trace.go:171","msg":"trace[19331186] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"236.713396ms","start":"2026-03-18T05:43:21.306142Z","end":"2026-03-18T05:43:21.542855Z","steps":["trace[19331186] 'process raft request' (duration: 236.516622ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:21.543155Z","caller":"traceutil/trace.go:171","msg":"trace[1555961423] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"236.827359ms","start":"2026-03-18T05:43:21.306272Z","end":"2026-03-18T05:43:21.5431Z","steps":["trace[1555961423] 'process raft request' (duration: 236.541842ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:21.543237Z","caller":"traceutil/trace.go:171","msg":"trace[393737526] linearizableReadLoop","detail":"{readStateIndex:5035; appliedIndex:5033; }","duration":"236.984022ms","start":"2026-03-18T05:43:21.306243Z","end":"2026-03-18T05:43:21.543227Z","steps":["trace[393737526] 'read index received' (duration: 235.137398ms)","trace[393737526] 'applied index is now lower than readState.Index' (duration: 1.845874ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:21.543288Z","caller":"traceutil/trace.go:171","msg":"trace[491724227] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"236.056729ms","start":"2026-03-18T05:43:21.307225Z","end":"2026-03-18T05:43:21.543282Z","steps":["trace[491724227] 'process raft request' (duration: 235.865705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:21.543581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.30546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70780"} {"level":"info","ts":"2026-03-18T05:43:21.54361Z","caller":"traceutil/trace.go:171","msg":"trace[315394354] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:4851; }","duration":"237.418563ms","start":"2026-03-18T05:43:21.306185Z","end":"2026-03-18T05:43:21.543604Z","steps":["trace[315394354] 'agreement among raft nodes before linearized reading' (duration: 237.176027ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:21.543172Z","caller":"traceutil/trace.go:171","msg":"trace[2013244876] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"235.938747ms","start":"2026-03-18T05:43:21.307195Z","end":"2026-03-18T05:43:21.543134Z","steps":["trace[2013244876] 'process raft request' (duration: 235.806914ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:28.745508Z","caller":"traceutil/trace.go:171","msg":"trace[1608565043] linearizableReadLoop","detail":"{readStateIndex:5125; appliedIndex:5124; }","duration":"120.464304ms","start":"2026-03-18T05:43:28.625009Z","end":"2026-03-18T05:43:28.745473Z","steps":["trace[1608565043] 'read index received' (duration: 120.172727ms)","trace[1608565043] 'applied index is now lower than readState.Index' (duration: 290.296µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:28.745655Z","caller":"traceutil/trace.go:171","msg":"trace[598824350] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"150.091213ms","start":"2026-03-18T05:43:28.595512Z","end":"2026-03-18T05:43:28.745603Z","steps":["trace[598824350] 'process raft request' (duration: 149.752175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:28.746162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.110709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-18T05:43:28.746228Z","caller":"traceutil/trace.go:171","msg":"trace[38230984] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4936; }","duration":"121.262742ms","start":"2026-03-18T05:43:28.62495Z","end":"2026-03-18T05:43:28.746213Z","steps":["trace[38230984] 'agreement among raft nodes before linearized reading' (duration: 120.670478ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:29.657381Z","caller":"traceutil/trace.go:171","msg":"trace[495431189] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"452.253654ms","start":"2026-03-18T05:43:29.205092Z","end":"2026-03-18T05:43:29.657345Z","steps":["trace[495431189] 'process raft request' (duration: 449.940058ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:29.65747Z","caller":"traceutil/trace.go:171","msg":"trace[1213606264] linearizableReadLoop","detail":"{readStateIndex:5168; appliedIndex:5167; }","duration":"450.931162ms","start":"2026-03-18T05:43:29.206478Z","end":"2026-03-18T05:43:29.657409Z","steps":["trace[1213606264] 'read index received' (duration: 448.651839ms)","trace[1213606264] 'applied index is now lower than readState.Index' (duration: 2.273503ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:43:29.657571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:29.205032Z","time spent":"452.443718ms","remote":"127.0.0.1:41308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9560,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:43:29.657911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.420851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-03-18T05:43:29.65797Z","caller":"traceutil/trace.go:171","msg":"trace[1043159985] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4981; }","duration":"450.519633ms","start":"2026-03-18T05:43:29.207438Z","end":"2026-03-18T05:43:29.657957Z","steps":["trace[1043159985] 'agreement among raft nodes before linearized reading' (duration: 450.302107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:29.658004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:29.207427Z","time spent":"450.569184ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1343,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" "} {"level":"warn","ts":"2026-03-18T05:43:29.65807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.060149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-03-18T05:43:29.658144Z","caller":"traceutil/trace.go:171","msg":"trace[48409550] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"314.57429ms","start":"2026-03-18T05:43:29.343502Z","end":"2026-03-18T05:43:29.658076Z","steps":["trace[48409550] 'process raft request' (duration: 313.999706ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:29.658165Z","caller":"traceutil/trace.go:171","msg":"trace[2131008661] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4981; }","duration":"218.232733ms","start":"2026-03-18T05:43:29.43991Z","end":"2026-03-18T05:43:29.658143Z","steps":["trace[2131008661] 'agreement among raft nodes before linearized reading' (duration: 217.969707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:29.658221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.732432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11d10aeb-de00-4f88-bfe9-87a5f0dc8f18\" ","response":"range_response_count:1 size:2580"} {"level":"warn","ts":"2026-03-18T05:43:29.658237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.354357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-03-18T05:43:29.658268Z","caller":"traceutil/trace.go:171","msg":"trace[1538947023] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11d10aeb-de00-4f88-bfe9-87a5f0dc8f18; range_end:; response_count:1; response_revision:4981; }","duration":"451.804724ms","start":"2026-03-18T05:43:29.206447Z","end":"2026-03-18T05:43:29.658252Z","steps":["trace[1538947023] 'agreement among raft nodes before linearized reading' (duration: 451.102407ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:29.658277Z","caller":"traceutil/trace.go:171","msg":"trace[854674996] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4981; }","duration":"424.437179ms","start":"2026-03-18T05:43:29.233831Z","end":"2026-03-18T05:43:29.658268Z","steps":["trace[854674996] 'agreement among raft nodes before linearized reading' (duration: 424.308226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:29.658279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:29.343477Z","time spent":"314.735183ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:43:29.658298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:29.206433Z","time spent":"451.858275ms","remote":"127.0.0.1:41040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":2602,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11d10aeb-de00-4f88-bfe9-87a5f0dc8f18\" "} {"level":"warn","ts":"2026-03-18T05:43:29.658302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:29.233812Z","time spent":"424.4852ms","remote":"127.0.0.1:42122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1839,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-03-18T05:43:29.657603Z","caller":"traceutil/trace.go:171","msg":"trace[840885049] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"340.216352ms","start":"2026-03-18T05:43:29.317349Z","end":"2026-03-18T05:43:29.657565Z","steps":["trace[840885049] 'process raft request' (duration: 340.048148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:29.661318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:29.317313Z","time spent":"343.887251ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:43:31.402399Z","caller":"traceutil/trace.go:171","msg":"trace[611598446] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5192; }","duration":"111.95335ms","start":"2026-03-18T05:43:31.290424Z","end":"2026-03-18T05:43:31.402377Z","steps":["trace[611598446] 'read index received' (duration: 77.387933ms)","trace[611598446] 'applied index is now lower than readState.Index' (duration: 34.564127ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:31.402543Z","caller":"traceutil/trace.go:171","msg":"trace[1722966743] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"117.690457ms","start":"2026-03-18T05:43:31.284809Z","end":"2026-03-18T05:43:31.4025Z","steps":["trace[1722966743] 'process raft request' (duration: 83.086719ms)","trace[1722966743] 'compare' (duration: 34.373022ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:43:31.402775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.316438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-03-18T05:43:31.402902Z","caller":"traceutil/trace.go:171","msg":"trace[681920967] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5004; }","duration":"112.523913ms","start":"2026-03-18T05:43:31.290341Z","end":"2026-03-18T05:43:31.402865Z","steps":["trace[681920967] 'agreement among raft nodes before linearized reading' (duration: 112.173155ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:38.892257Z","caller":"traceutil/trace.go:171","msg":"trace[900629575] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"102.676658ms","start":"2026-03-18T05:43:38.789535Z","end":"2026-03-18T05:43:38.892212Z","steps":["trace[900629575] 'process raft request' (duration: 102.320939ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:39.911659Z","caller":"traceutil/trace.go:171","msg":"trace[998533666] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"100.527316ms","start":"2026-03-18T05:43:39.811088Z","end":"2026-03-18T05:43:39.911615Z","steps":["trace[998533666] 'process raft request' (duration: 100.210919ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.10178Z","caller":"traceutil/trace.go:171","msg":"trace[502532422] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"396.269684ms","start":"2026-03-18T05:43:43.705474Z","end":"2026-03-18T05:43:44.101743Z","steps":["trace[502532422] 'process raft request' (duration: 396.023328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.102017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.705447Z","time spent":"396.442759ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:43:44.109881Z","caller":"traceutil/trace.go:171","msg":"trace[748895634] linearizableReadLoop","detail":"{readStateIndex:5375; appliedIndex:5373; }","duration":"371.706856ms","start":"2026-03-18T05:43:43.738151Z","end":"2026-03-18T05:43:44.109858Z","steps":["trace[748895634] 'read index received' (duration: 363.326095ms)","trace[748895634] 'applied index is now lower than readState.Index' (duration: 8.379881ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:43:44.110041Z","caller":"traceutil/trace.go:171","msg":"trace[1212545] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"397.022252ms","start":"2026-03-18T05:43:43.712972Z","end":"2026-03-18T05:43:44.109994Z","steps":["trace[1212545] 'process raft request' (duration: 396.704534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.110309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.712945Z","time spent":"397.212556ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:43:44.110312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.142751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:43:44.110404Z","caller":"traceutil/trace.go:171","msg":"trace[1658536903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5180; }","duration":"368.333346ms","start":"2026-03-18T05:43:43.74205Z","end":"2026-03-18T05:43:44.110383Z","steps":["trace[1658536903] 'agreement among raft nodes before linearized reading' (duration: 368.064479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.110436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.885719ms","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-03-18T05:43:44.11046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.742Z","time spent":"368.450669ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T05:43:44.110518Z","caller":"traceutil/trace.go:171","msg":"trace[1766803655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5180; }","duration":"363.982442ms","start":"2026-03-18T05:43:43.746517Z","end":"2026-03-18T05:43:44.110499Z","steps":["trace[1766803655] 'agreement among raft nodes before linearized reading' (duration: 363.639683ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.110561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.74651Z","time spent":"364.042663ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:43:44.110552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.567605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-03-18T05:43:44.110697Z","caller":"traceutil/trace.go:171","msg":"trace[1931368302] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5180; }","duration":"323.800461ms","start":"2026-03-18T05:43:43.786869Z","end":"2026-03-18T05:43:44.110669Z","steps":["trace[1931368302] 'agreement among raft nodes before linearized reading' (duration: 323.3568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.110713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.815467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-03-18T05:43:44.110741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.07988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-03-18T05:43:44.110769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.786848Z","time spent":"323.909373ms","remote":"127.0.0.1:52496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-03-18T05:43:44.110762Z","caller":"traceutil/trace.go:171","msg":"trace[194858305] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5180; }","duration":"210.884899ms","start":"2026-03-18T05:43:43.899863Z","end":"2026-03-18T05:43:44.110748Z","steps":["trace[194858305] 'agreement among raft nodes before linearized reading' (duration: 210.354326ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.110808Z","caller":"traceutil/trace.go:171","msg":"trace[2142720157] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:5180; }","duration":"358.193084ms","start":"2026-03-18T05:43:43.752595Z","end":"2026-03-18T05:43:44.110788Z","steps":["trace[2142720157] 'agreement among raft nodes before linearized reading' (duration: 358.005779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.11087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.752575Z","time spent":"358.282455ms","remote":"127.0.0.1:41040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-hj96p\" "} {"level":"warn","ts":"2026-03-18T05:43:44.110559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.10036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-03-18T05:43:44.110927Z","caller":"traceutil/trace.go:171","msg":"trace[1072705636] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5180; }","duration":"284.532961ms","start":"2026-03-18T05:43:43.826377Z","end":"2026-03-18T05:43:44.11091Z","steps":["trace[1072705636] 'agreement among raft nodes before linearized reading' (duration: 283.812104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.11031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.610444ms","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-03-18T05:43:44.111044Z","caller":"traceutil/trace.go:171","msg":"trace[808463804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5180; }","duration":"367.387733ms","start":"2026-03-18T05:43:43.743632Z","end":"2026-03-18T05:43:44.111019Z","steps":["trace[808463804] 'agreement among raft nodes before linearized reading' (duration: 366.492531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.111096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.743621Z","time spent":"367.462395ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T05:43:44.110588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.419704ms","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-03-18T05:43:44.111185Z","caller":"traceutil/trace.go:171","msg":"trace[1083504837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5180; }","duration":"373.07776ms","start":"2026-03-18T05:43:43.738091Z","end":"2026-03-18T05:43:44.111169Z","steps":["trace[1083504837] 'agreement among raft nodes before linearized reading' (duration: 371.985093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.11126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.738069Z","time spent":"373.153111ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T05:43:44.112133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.723044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T05:43:44.112202Z","caller":"traceutil/trace.go:171","msg":"trace[656787741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5180; }","duration":"368.822677ms","start":"2026-03-18T05:43:43.743363Z","end":"2026-03-18T05:43:44.112185Z","steps":["trace[656787741] 'agreement among raft nodes before linearized reading' (duration: 366.780947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.11224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:43:43.743349Z","time spent":"368.880398ms","remote":"127.0.0.1:41138","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-03-18T05:43:44.247808Z","caller":"traceutil/trace.go:171","msg":"trace[125750708] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"131.481687ms","start":"2026-03-18T05:43:44.1163Z","end":"2026-03-18T05:43:44.247781Z","steps":["trace[125750708] 'process raft request' (duration: 131.276562ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.247971Z","caller":"traceutil/trace.go:171","msg":"trace[224042335] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"130.841982ms","start":"2026-03-18T05:43:44.117101Z","end":"2026-03-18T05:43:44.247943Z","steps":["trace[224042335] 'process raft request' (duration: 130.609216ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.247964Z","caller":"traceutil/trace.go:171","msg":"trace[1351831334] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"131.550318ms","start":"2026-03-18T05:43:44.116381Z","end":"2026-03-18T05:43:44.247932Z","steps":["trace[1351831334] 'process raft request' (duration: 131.284922ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.248045Z","caller":"traceutil/trace.go:171","msg":"trace[100470959] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"132.246355ms","start":"2026-03-18T05:43:44.115787Z","end":"2026-03-18T05:43:44.248034Z","steps":["trace[100470959] 'process raft request' (duration: 125.577084ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.247932Z","caller":"traceutil/trace.go:171","msg":"trace[1090812114] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"122.36516ms","start":"2026-03-18T05:43:44.12554Z","end":"2026-03-18T05:43:44.247905Z","steps":["trace[1090812114] 'process raft request' (duration: 122.224906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.248087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.32202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81887"} {"level":"info","ts":"2026-03-18T05:43:44.248245Z","caller":"traceutil/trace.go:171","msg":"trace[1319989417] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5186; }","duration":"130.513754ms","start":"2026-03-18T05:43:44.117713Z","end":"2026-03-18T05:43:44.248226Z","steps":["trace[1319989417] 'agreement among raft nodes before linearized reading' (duration: 130.127925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:43:44.248137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.839574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:43:44.248456Z","caller":"traceutil/trace.go:171","msg":"trace[2009007540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5186; }","duration":"129.160791ms","start":"2026-03-18T05:43:44.119241Z","end":"2026-03-18T05:43:44.248402Z","steps":["trace[2009007540] 'agreement among raft nodes before linearized reading' (duration: 128.782272ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.403911Z","caller":"traceutil/trace.go:171","msg":"trace[746843397] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"148.026222ms","start":"2026-03-18T05:43:44.255865Z","end":"2026-03-18T05:43:44.403891Z","steps":["trace[746843397] 'process raft request' (duration: 147.825677ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.404212Z","caller":"traceutil/trace.go:171","msg":"trace[47874907] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"148.120604ms","start":"2026-03-18T05:43:44.256071Z","end":"2026-03-18T05:43:44.404191Z","steps":["trace[47874907] 'process raft request' (duration: 147.989391ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:43:44.404394Z","caller":"traceutil/trace.go:171","msg":"trace[838487918] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"148.33419ms","start":"2026-03-18T05:43:44.256016Z","end":"2026-03-18T05:43:44.40435Z","steps":["trace[838487918] 'process raft request' (duration: 147.820227ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:44:36.577828Z","caller":"traceutil/trace.go:171","msg":"trace[960270673] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"110.70148ms","start":"2026-03-18T05:44:36.46706Z","end":"2026-03-18T05:44:36.577761Z","steps":["trace[960270673] 'process raft request' (duration: 22.730254ms)","trace[960270673] 'compare' (duration: 87.828353ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:44:58.698599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2783} {"level":"info","ts":"2026-03-18T05:44:58.764831Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2783,"took":"63.275045ms","hash":2605856062,"current-db-size-bytes":41648128,"current-db-size":"42 MB","current-db-size-in-use-bytes":31178752,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-18T05:44:58.764972Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2605856062,"revision":2783,"compact-revision":-1} {"level":"info","ts":"2026-03-18T05:49:33.986987Z","caller":"traceutil/trace.go:171","msg":"trace[1304189061] linearizableReadLoop","detail":"{readStateIndex:7938; appliedIndex:7937; }","duration":"320.253311ms","start":"2026-03-18T05:49:33.666705Z","end":"2026-03-18T05:49:33.986958Z","steps":["trace[1304189061] 'read index received' (duration: 320.142028ms)","trace[1304189061] 'applied index is now lower than readState.Index' (duration: 107.233µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:33.987072Z","caller":"traceutil/trace.go:171","msg":"trace[740043640] transaction","detail":"{read_only:false; response_revision:7658; number_of_response:1; }","duration":"346.944382ms","start":"2026-03-18T05:49:33.640082Z","end":"2026-03-18T05:49:33.987027Z","steps":["trace[740043640] 'process raft request' (duration: 346.697576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:33.987129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.435505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:33.987169Z","caller":"traceutil/trace.go:171","msg":"trace[1764807025] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7658; }","duration":"320.527847ms","start":"2026-03-18T05:49:33.666629Z","end":"2026-03-18T05:49:33.987157Z","steps":["trace[1764807025] 'agreement among raft nodes before linearized reading' (duration: 320.442025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:33.987204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:33.666608Z","time spent":"320.587849ms","remote":"127.0.0.1:41306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-03-18T05:49:33.987253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:33.640053Z","time spent":"347.107736ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:34.019831Z","caller":"traceutil/trace.go:171","msg":"trace[587391464] transaction","detail":"{read_only:false; response_revision:7659; number_of_response:1; }","duration":"299.4636ms","start":"2026-03-18T05:49:33.720313Z","end":"2026-03-18T05:49:34.019776Z","steps":["trace[587391464] 'process raft request' (duration: 299.260676ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.224422Z","caller":"traceutil/trace.go:171","msg":"trace[2062080745] transaction","detail":"{read_only:false; response_revision:7715; number_of_response:1; }","duration":"182.15663ms","start":"2026-03-18T05:49:46.042226Z","end":"2026-03-18T05:49:46.224383Z","steps":["trace[2062080745] 'process raft request' (duration: 181.973586ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.299274Z","caller":"traceutil/trace.go:171","msg":"trace[705908973] linearizableReadLoop","detail":"{readStateIndex:7999; appliedIndex:7997; }","duration":"180.278076ms","start":"2026-03-18T05:49:46.118958Z","end":"2026-03-18T05:49:46.299236Z","steps":["trace[705908973] 'read index received' (duration: 105.215673ms)","trace[705908973] 'applied index is now lower than readState.Index' (duration: 75.061083ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:46.299428Z","caller":"traceutil/trace.go:171","msg":"trace[537947362] transaction","detail":"{read_only:false; response_revision:7716; number_of_response:1; }","duration":"239.262659ms","start":"2026-03-18T05:49:46.060105Z","end":"2026-03-18T05:49:46.299367Z","steps":["trace[537947362] 'process raft request' (duration: 238.921931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:46.299486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.521392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:46.299599Z","caller":"traceutil/trace.go:171","msg":"trace[1746289471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7716; }","duration":"180.729627ms","start":"2026-03-18T05:49:46.118838Z","end":"2026-03-18T05:49:46.299567Z","steps":["trace[1746289471] 'agreement among raft nodes before linearized reading' (duration: 180.540293ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.699266Z","caller":"traceutil/trace.go:171","msg":"trace[768445244] transaction","detail":"{read_only:false; response_revision:7717; number_of_response:1; }","duration":"394.915455ms","start":"2026-03-18T05:49:46.304316Z","end":"2026-03-18T05:49:46.699232Z","steps":["trace[768445244] 'process raft request' (duration: 388.756659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:46.699454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:46.30429Z","time spent":"395.066577ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:46.701766Z","caller":"traceutil/trace.go:171","msg":"trace[1539728699] transaction","detail":"{read_only:false; response_revision:7719; number_of_response:1; }","duration":"397.054495ms","start":"2026-03-18T05:49:46.30467Z","end":"2026-03-18T05:49:46.701724Z","steps":["trace[1539728699] 'process raft request' (duration: 396.960672ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.702086Z","caller":"traceutil/trace.go:171","msg":"trace[618943026] transaction","detail":"{read_only:false; response_revision:7720; number_of_response:1; }","duration":"397.351112ms","start":"2026-03-18T05:49:46.30471Z","end":"2026-03-18T05:49:46.702061Z","steps":["trace[618943026] 'process raft request' (duration: 396.964083ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.702188Z","caller":"traceutil/trace.go:171","msg":"trace[752233253] linearizableReadLoop","detail":"{readStateIndex:8004; appliedIndex:7999; }","duration":"139.74836ms","start":"2026-03-18T05:49:46.562393Z","end":"2026-03-18T05:49:46.702142Z","steps":["trace[752233253] 'read index received' (duration: 130.772769ms)","trace[752233253] 'applied index is now lower than readState.Index' (duration: 8.973841ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:46.702254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:46.304699Z","time spent":"397.504416ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:46.702232Z","caller":"traceutil/trace.go:171","msg":"trace[1316820222] transaction","detail":"{read_only:false; response_revision:7721; number_of_response:1; }","duration":"397.484665ms","start":"2026-03-18T05:49:46.304715Z","end":"2026-03-18T05:49:46.7022Z","steps":["trace[1316820222] 'process raft request' (duration: 397.260349ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:46.701904Z","caller":"traceutil/trace.go:171","msg":"trace[1280428576] transaction","detail":"{read_only:false; response_revision:7718; number_of_response:1; }","duration":"397.172637ms","start":"2026-03-18T05:49:46.304669Z","end":"2026-03-18T05:49:46.701842Z","steps":["trace[1280428576] 'process raft request' (duration: 396.809059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:46.702038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:46.30465Z","time spent":"397.221609ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:49:46.702399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:46.304692Z","time spent":"397.620708ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:49:46.702505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.287869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:49:46.702602Z","caller":"traceutil/trace.go:171","msg":"trace[1180511854] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7721; }","duration":"103.420762ms","start":"2026-03-18T05:49:46.599165Z","end":"2026-03-18T05:49:46.702586Z","steps":["trace[1180511854] 'agreement among raft nodes before linearized reading' (duration: 103.252248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:46.702596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.168029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-03-18T05:49:46.702515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:46.304656Z","time spent":"397.722441ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:46.70266Z","caller":"traceutil/trace.go:171","msg":"trace[2081139580] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:7721; }","duration":"140.333664ms","start":"2026-03-18T05:49:46.562308Z","end":"2026-03-18T05:49:46.702642Z","steps":["trace[2081139580] 'agreement among raft nodes before linearized reading' (duration: 140.085688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:47.632446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.490537ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-18T05:49:48.81687Z","caller":"traceutil/trace.go:171","msg":"trace[29282935] transaction","detail":"{read_only:false; response_revision:7723; number_of_response:1; }","duration":"585.352641ms","start":"2026-03-18T05:49:48.231487Z","end":"2026-03-18T05:49:48.81684Z","steps":["trace[29282935] 'process raft request' (duration: 585.124065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.8171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:48.231455Z","time spent":"585.516405ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:49:48.901144Z","caller":"traceutil/trace.go:171","msg":"trace[851435696] linearizableReadLoop","detail":"{readStateIndex:8008; appliedIndex:8006; }","duration":"442.825006ms","start":"2026-03-18T05:49:48.458295Z","end":"2026-03-18T05:49:48.90112Z","steps":["trace[851435696] 'read index received' (duration: 358.259019ms)","trace[851435696] 'applied index is now lower than readState.Index' (duration: 84.564577ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:48.901286Z","caller":"traceutil/trace.go:171","msg":"trace[1226305514] transaction","detail":"{read_only:false; response_revision:7724; number_of_response:1; }","duration":"595.09976ms","start":"2026-03-18T05:49:48.30614Z","end":"2026-03-18T05:49:48.901239Z","steps":["trace[1226305514] 'process raft request' (duration: 542.343145ms)","trace[1226305514] 'compare' (duration: 52.501019ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:49:48.90137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.04189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:49:48.901475Z","caller":"traceutil/trace.go:171","msg":"trace[61189473] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:7724; }","duration":"443.204524ms","start":"2026-03-18T05:49:48.458244Z","end":"2026-03-18T05:49:48.901448Z","steps":["trace[61189473] 'agreement among raft nodes before linearized reading' (duration: 443.01699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.901485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:48.306093Z","time spent":"595.284075ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:49:48.901545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:48.458222Z","time spent":"443.303676ms","remote":"127.0.0.1:52570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"warn","ts":"2026-03-18T05:49:48.920151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.525428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:49:48.920218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.511731ms","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-03-18T05:49:48.920255Z","caller":"traceutil/trace.go:171","msg":"trace[1994164245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7725; }","duration":"213.590893ms","start":"2026-03-18T05:49:48.706655Z","end":"2026-03-18T05:49:48.920246Z","steps":["trace[1994164245] 'agreement among raft nodes before linearized reading' (duration: 213.376758ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.920238Z","caller":"traceutil/trace.go:171","msg":"trace[1953734198] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:7725; }","duration":"331.705673ms","start":"2026-03-18T05:49:48.588514Z","end":"2026-03-18T05:49:48.920219Z","steps":["trace[1953734198] 'agreement among raft nodes before linearized reading' (duration: 331.493348ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.920293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:49:48.58849Z","time spent":"331.792224ms","remote":"127.0.0.1:50034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-03-18T05:49:48.920377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.047504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T05:49:48.920477Z","caller":"traceutil/trace.go:171","msg":"trace[1940688695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7725; }","duration":"214.188978ms","start":"2026-03-18T05:49:48.706267Z","end":"2026-03-18T05:49:48.920456Z","steps":["trace[1940688695] 'agreement among raft nodes before linearized reading' (duration: 213.873821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.920444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.930042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-18T05:49:48.920555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.305803ms","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-03-18T05:49:48.920567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.091627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:49:48.920603Z","caller":"traceutil/trace.go:171","msg":"trace[1139321481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7725; }","duration":"212.381915ms","start":"2026-03-18T05:49:48.708209Z","end":"2026-03-18T05:49:48.920591Z","steps":["trace[1139321481] 'agreement among raft nodes before linearized reading' (duration: 212.255662ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.920606Z","caller":"traceutil/trace.go:171","msg":"trace[783322858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7725; }","duration":"217.167908ms","start":"2026-03-18T05:49:48.703429Z","end":"2026-03-18T05:49:48.920597Z","steps":["trace[783322858] 'agreement among raft nodes before linearized reading' (duration: 217.087357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.920592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.050626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-18T05:49:48.920661Z","caller":"traceutil/trace.go:171","msg":"trace[342883207] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:7725; }","duration":"206.137367ms","start":"2026-03-18T05:49:48.714504Z","end":"2026-03-18T05:49:48.920641Z","steps":["trace[342883207] 'agreement among raft nodes before linearized reading' (duration: 205.919482ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:48.920713Z","caller":"traceutil/trace.go:171","msg":"trace[864435984] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7725; }","duration":"214.308581ms","start":"2026-03-18T05:49:48.706386Z","end":"2026-03-18T05:49:48.920694Z","steps":["trace[864435984] 'agreement among raft nodes before linearized reading' (duration: 213.797849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:48.920492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.791858ms","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-03-18T05:49:48.921043Z","caller":"traceutil/trace.go:171","msg":"trace[1390741850] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:7725; }","duration":"286.377421ms","start":"2026-03-18T05:49:48.634647Z","end":"2026-03-18T05:49:48.921024Z","steps":["trace[1390741850] 'agreement among raft nodes before linearized reading' (duration: 285.811318ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:51.021342Z","caller":"traceutil/trace.go:171","msg":"trace[375755727] transaction","detail":"{read_only:false; response_revision:7734; number_of_response:1; }","duration":"112.625479ms","start":"2026-03-18T05:49:50.908679Z","end":"2026-03-18T05:49:51.021304Z","steps":["trace[375755727] 'process raft request' (duration: 83.435109ms)","trace[375755727] 'compare' (duration: 29.026136ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:51.034162Z","caller":"traceutil/trace.go:171","msg":"trace[538928723] transaction","detail":"{read_only:false; response_revision:7735; number_of_response:1; }","duration":"106.655048ms","start":"2026-03-18T05:49:50.927446Z","end":"2026-03-18T05:49:51.034101Z","steps":["trace[538928723] 'process raft request' (duration: 106.370331ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:51.173506Z","caller":"traceutil/trace.go:171","msg":"trace[611909707] transaction","detail":"{read_only:false; response_revision:7738; number_of_response:1; }","duration":"134.350032ms","start":"2026-03-18T05:49:51.03913Z","end":"2026-03-18T05:49:51.17348Z","steps":["trace[611909707] 'process raft request' (duration: 134.24238ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:51.173684Z","caller":"traceutil/trace.go:171","msg":"trace[697705365] transaction","detail":"{read_only:false; response_revision:7737; number_of_response:1; }","duration":"134.501606ms","start":"2026-03-18T05:49:51.03913Z","end":"2026-03-18T05:49:51.173631Z","steps":["trace[697705365] 'process raft request' (duration: 133.684167ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:51.176575Z","caller":"traceutil/trace.go:171","msg":"trace[475134604] transaction","detail":"{read_only:false; response_revision:7739; number_of_response:1; }","duration":"137.350983ms","start":"2026-03-18T05:49:51.039201Z","end":"2026-03-18T05:49:51.176552Z","steps":["trace[475134604] 'process raft request' (duration: 137.176219ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:51.176877Z","caller":"traceutil/trace.go:171","msg":"trace[685792575] transaction","detail":"{read_only:false; response_revision:7741; number_of_response:1; }","duration":"136.205506ms","start":"2026-03-18T05:49:51.040642Z","end":"2026-03-18T05:49:51.176848Z","steps":["trace[685792575] 'process raft request' (duration: 136.078993ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:51.17686Z","caller":"traceutil/trace.go:171","msg":"trace[457517104] linearizableReadLoop","detail":"{readStateIndex:8023; appliedIndex:8020; }","duration":"136.911073ms","start":"2026-03-18T05:49:51.03992Z","end":"2026-03-18T05:49:51.176831Z","steps":["trace[457517104] 'read index received' (duration: 132.94162ms)","trace[457517104] 'applied index is now lower than readState.Index' (duration: 3.968043ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:49:51.176907Z","caller":"traceutil/trace.go:171","msg":"trace[967541542] transaction","detail":"{read_only:false; response_revision:7740; number_of_response:1; }","duration":"136.681897ms","start":"2026-03-18T05:49:51.040176Z","end":"2026-03-18T05:49:51.176858Z","steps":["trace[967541542] 'process raft request' (duration: 136.332979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:49:51.177532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.582389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224135"} {"level":"info","ts":"2026-03-18T05:49:51.177619Z","caller":"traceutil/trace.go:171","msg":"trace[2118115705] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7741; }","duration":"137.714403ms","start":"2026-03-18T05:49:51.039877Z","end":"2026-03-18T05:49:51.177592Z","steps":["trace[2118115705] 'agreement among raft nodes before linearized reading' (duration: 137.066067ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:49:58.707954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5759} {"level":"info","ts":"2026-03-18T05:49:58.804116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5759,"took":"94.198274ms","hash":1687127283,"current-db-size-bytes":42369024,"current-db-size":"42 MB","current-db-size-in-use-bytes":23572480,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-18T05:49:58.804202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1687127283,"revision":5759,"compact-revision":2783} {"level":"info","ts":"2026-03-18T05:50:19.608607Z","caller":"traceutil/trace.go:171","msg":"trace[359099596] linearizableReadLoop","detail":"{readStateIndex:8217; appliedIndex:8216; }","duration":"486.373464ms","start":"2026-03-18T05:50:19.12221Z","end":"2026-03-18T05:50:19.608583Z","steps":["trace[359099596] 'read index received' (duration: 486.315773ms)","trace[359099596] 'applied index is now lower than readState.Index' (duration: 56.521µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:19.608718Z","caller":"traceutil/trace.go:171","msg":"trace[782406234] transaction","detail":"{read_only:false; response_revision:7924; number_of_response:1; }","duration":"673.051922ms","start":"2026-03-18T05:50:18.935638Z","end":"2026-03-18T05:50:19.60869Z","steps":["trace[782406234] 'process raft request' (duration: 672.813725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.608802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.578989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:19.608882Z","caller":"traceutil/trace.go:171","msg":"trace[341264186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7924; }","duration":"487.541651ms","start":"2026-03-18T05:50:19.121323Z","end":"2026-03-18T05:50:19.608865Z","steps":["trace[341264186] 'agreement among raft nodes before linearized reading' (duration: 487.391887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.608873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:18.935618Z","time spent":"673.169684ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:19.608915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.121304Z","time spent":"487.605473ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T05:50:19.654594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.116331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-18T05:50:19.65462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.886744ms","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-03-18T05:50:19.65468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.175066ms","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-03-18T05:50:19.654733Z","caller":"traceutil/trace.go:171","msg":"trace[1065140552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7926; }","duration":"330.245047ms","start":"2026-03-18T05:50:19.324475Z","end":"2026-03-18T05:50:19.65472Z","steps":["trace[1065140552] 'agreement among raft nodes before linearized reading' (duration: 330.101834ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:19.654737Z","caller":"traceutil/trace.go:171","msg":"trace[1172503530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7926; }","duration":"326.039907ms","start":"2026-03-18T05:50:19.328673Z","end":"2026-03-18T05:50:19.654713Z","steps":["trace[1172503530] 'agreement among raft nodes before linearized reading' (duration: 325.74557ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:19.65475Z","caller":"traceutil/trace.go:171","msg":"trace[385652614] transaction","detail":"{read_only:false; response_revision:7926; number_of_response:1; }","duration":"485.992003ms","start":"2026-03-18T05:50:19.168749Z","end":"2026-03-18T05:50:19.654741Z","steps":["trace[385652614] 'process raft request' (duration: 485.588524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.654709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.295258ms","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-03-18T05:50:19.654773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.324468Z","time spent":"330.294879ms","remote":"127.0.0.1:41138","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-03-18T05:50:19.654816Z","caller":"traceutil/trace.go:171","msg":"trace[161293342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7926; }","duration":"338.461262ms","start":"2026-03-18T05:50:19.316328Z","end":"2026-03-18T05:50:19.654789Z","steps":["trace[161293342] 'agreement among raft nodes before linearized reading' (duration: 338.146934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.654848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.168737Z","time spent":"486.044875ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:19.654889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.813778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-18T05:50:19.654929Z","caller":"traceutil/trace.go:171","msg":"trace[655158589] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:7926; }","duration":"318.870909ms","start":"2026-03-18T05:50:19.33605Z","end":"2026-03-18T05:50:19.654921Z","steps":["trace[655158589] 'agreement among raft nodes before linearized reading' (duration: 318.763056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.654787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.328664Z","time spent":"326.114039ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T05:50:19.654958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.33604Z","time spent":"318.912159ms","remote":"127.0.0.1:41040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-hj96p\" "} {"level":"warn","ts":"2026-03-18T05:50:19.65501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.874802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:50:19.655052Z","caller":"traceutil/trace.go:171","msg":"trace[1564445850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7926; }","duration":"330.954004ms","start":"2026-03-18T05:50:19.324086Z","end":"2026-03-18T05:50:19.65504Z","steps":["trace[1564445850] 'agreement among raft nodes before linearized reading' (duration: 330.832992ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:19.655067Z","caller":"traceutil/trace.go:171","msg":"trace[307991523] transaction","detail":"{read_only:false; response_revision:7925; number_of_response:1; }","duration":"490.216324ms","start":"2026-03-18T05:50:19.164837Z","end":"2026-03-18T05:50:19.655054Z","steps":["trace[307991523] 'process raft request' (duration: 488.732129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.655099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.324065Z","time spent":"331.023266ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T05:50:19.65469Z","caller":"traceutil/trace.go:171","msg":"trace[1832507997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7926; }","duration":"329.247084ms","start":"2026-03-18T05:50:19.325423Z","end":"2026-03-18T05:50:19.65467Z","steps":["trace[1832507997] 'agreement among raft nodes before linearized reading' (duration: 329.023849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:19.654887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.316302Z","time spent":"338.570074ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:50:19.655246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.325412Z","time spent":"329.793687ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T05:50:19.655157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:19.164813Z","time spent":"490.283366ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:21.779823Z","caller":"traceutil/trace.go:171","msg":"trace[601481010] transaction","detail":"{read_only:false; response_revision:7940; number_of_response:1; }","duration":"100.541034ms","start":"2026-03-18T05:50:21.67925Z","end":"2026-03-18T05:50:21.779791Z","steps":["trace[601481010] 'process raft request' (duration: 100.435681ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:21.779877Z","caller":"traceutil/trace.go:171","msg":"trace[1731831204] transaction","detail":"{read_only:false; response_revision:7939; number_of_response:1; }","duration":"101.108437ms","start":"2026-03-18T05:50:21.678731Z","end":"2026-03-18T05:50:21.779839Z","steps":["trace[1731831204] 'process raft request' (duration: 100.767498ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:21.779969Z","caller":"traceutil/trace.go:171","msg":"trace[449948131] linearizableReadLoop","detail":"{readStateIndex:8232; appliedIndex:8230; }","duration":"100.963414ms","start":"2026-03-18T05:50:21.678984Z","end":"2026-03-18T05:50:21.779947Z","steps":["trace[449948131] 'read index received' (duration: 29.711851ms)","trace[449948131] 'applied index is now lower than readState.Index' (duration: 71.249693ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:21.780049Z","caller":"traceutil/trace.go:171","msg":"trace[234291132] transaction","detail":"{read_only:false; response_revision:7941; number_of_response:1; }","duration":"100.589416ms","start":"2026-03-18T05:50:21.679446Z","end":"2026-03-18T05:50:21.780036Z","steps":["trace[234291132] 'process raft request' (duration: 100.288349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:21.780146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.144998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-18T05:50:21.780182Z","caller":"traceutil/trace.go:171","msg":"trace[317978172] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:7941; }","duration":"101.21405ms","start":"2026-03-18T05:50:21.678958Z","end":"2026-03-18T05:50:21.780172Z","steps":["trace[317978172] 'agreement among raft nodes before linearized reading' (duration: 101.044236ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:23.286167Z","caller":"traceutil/trace.go:171","msg":"trace[779386519] linearizableReadLoop","detail":"{readStateIndex:8239; appliedIndex:8238; }","duration":"166.702646ms","start":"2026-03-18T05:50:23.119437Z","end":"2026-03-18T05:50:23.28614Z","steps":["trace[779386519] 'read index received' (duration: 166.373998ms)","trace[779386519] 'applied index is now lower than readState.Index' (duration: 327.308µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:23.28621Z","caller":"traceutil/trace.go:171","msg":"trace[1699979759] transaction","detail":"{read_only:false; response_revision:7945; number_of_response:1; }","duration":"260.979192ms","start":"2026-03-18T05:50:23.025202Z","end":"2026-03-18T05:50:23.286181Z","steps":["trace[1699979759] 'process raft request' (duration: 260.719386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:23.286451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.963521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:23.286551Z","caller":"traceutil/trace.go:171","msg":"trace[1542485706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7945; }","duration":"167.158256ms","start":"2026-03-18T05:50:23.119371Z","end":"2026-03-18T05:50:23.286529Z","steps":["trace[1542485706] 'agreement among raft nodes before linearized reading' (duration: 166.92036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:23.815943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.923129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:50:23.816032Z","caller":"traceutil/trace.go:171","msg":"trace[1529855906] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:7945; }","duration":"355.073763ms","start":"2026-03-18T05:50:23.460936Z","end":"2026-03-18T05:50:23.81601Z","steps":["trace[1529855906] 'count revisions from in-memory index tree' (duration: 354.824427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:23.816086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.460911Z","time spent":"355.158525ms","remote":"127.0.0.1:50022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-03-18T05:50:23.816087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.399242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:23.816168Z","caller":"traceutil/trace.go:171","msg":"trace[812905038] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:7945; }","duration":"286.551357ms","start":"2026-03-18T05:50:23.529596Z","end":"2026-03-18T05:50:23.816147Z","steps":["trace[812905038] 'count revisions from in-memory index tree' (duration: 286.283859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:23.816287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.683769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:50:23.816371Z","caller":"traceutil/trace.go:171","msg":"trace[1949585145] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:7945; }","duration":"178.796342ms","start":"2026-03-18T05:50:23.637555Z","end":"2026-03-18T05:50:23.816352Z","steps":["trace[1949585145] 'count revisions from in-memory index tree' (duration: 178.585937ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:23.816354Z","caller":"traceutil/trace.go:171","msg":"trace[2007439731] transaction","detail":"{read_only:false; response_revision:7946; number_of_response:1; }","duration":"171.92504ms","start":"2026-03-18T05:50:23.644399Z","end":"2026-03-18T05:50:23.816324Z","steps":["trace[2007439731] 'process raft request' (duration: 101.724372ms)","trace[2007439731] 'compare' (duration: 70.069385ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:24.207062Z","caller":"traceutil/trace.go:171","msg":"trace[1927442154] transaction","detail":"{read_only:false; response_revision:7947; number_of_response:1; }","duration":"533.384473ms","start":"2026-03-18T05:50:23.673642Z","end":"2026-03-18T05:50:24.207026Z","steps":["trace[1927442154] 'process raft request' (duration: 533.067646ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:24.207112Z","caller":"traceutil/trace.go:171","msg":"trace[1451165060] transaction","detail":"{read_only:false; response_revision:7948; number_of_response:1; }","duration":"527.777511ms","start":"2026-03-18T05:50:23.679315Z","end":"2026-03-18T05:50:24.207092Z","steps":["trace[1451165060] 'process raft request' (duration: 527.628617ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:24.207071Z","caller":"traceutil/trace.go:171","msg":"trace[1196711391] linearizableReadLoop","detail":"{readStateIndex:8242; appliedIndex:8239; }","duration":"480.614138ms","start":"2026-03-18T05:50:23.726404Z","end":"2026-03-18T05:50:24.207017Z","steps":["trace[1196711391] 'read index received' (duration: 19.702766ms)","trace[1196711391] 'applied index is now lower than readState.Index' (duration: 460.890122ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:24.207218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.679289Z","time spent":"527.857913ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:24.207216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.673615Z","time spent":"533.531807ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:50:24.207472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.081549ms","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-03-18T05:50:24.207496Z","caller":"traceutil/trace.go:171","msg":"trace[140754278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7948; }","duration":"481.10659ms","start":"2026-03-18T05:50:23.726384Z","end":"2026-03-18T05:50:24.20749Z","steps":["trace[140754278] 'agreement among raft nodes before linearized reading' (duration: 480.993877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.207512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.72637Z","time spent":"481.13886ms","remote":"127.0.0.1:41138","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-03-18T05:50:24.307818Z","caller":"traceutil/trace.go:171","msg":"trace[1530612923] linearizableReadLoop","detail":"{readStateIndex:8243; appliedIndex:8242; }","duration":"100.606505ms","start":"2026-03-18T05:50:24.207182Z","end":"2026-03-18T05:50:24.307789Z","steps":["trace[1530612923] 'read index received' (duration: 100.348469ms)","trace[1530612923] 'applied index is now lower than readState.Index' (duration: 255.856µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:24.307987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.376849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:24.308028Z","caller":"traceutil/trace.go:171","msg":"trace[647350096] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:7949; }","duration":"565.484561ms","start":"2026-03-18T05:50:23.742529Z","end":"2026-03-18T05:50:24.308014Z","steps":["trace[647350096] 'agreement among raft nodes before linearized reading' (duration: 565.382269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.308012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.670995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-03-18T05:50:24.308076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.742508Z","time spent":"565.558013ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"info","ts":"2026-03-18T05:50:24.308088Z","caller":"traceutil/trace.go:171","msg":"trace[2094960321] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:7949; }","duration":"482.784498ms","start":"2026-03-18T05:50:23.825286Z","end":"2026-03-18T05:50:24.30807Z","steps":["trace[2094960321] 'agreement among raft nodes before linearized reading' (duration: 482.530123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.308132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.825273Z","time spent":"482.8505ms","remote":"127.0.0.1:41040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-hj96p\" "} {"level":"info","ts":"2026-03-18T05:50:24.308358Z","caller":"traceutil/trace.go:171","msg":"trace[503137628] transaction","detail":"{read_only:false; response_revision:7949; number_of_response:1; }","duration":"222.166245ms","start":"2026-03-18T05:50:24.086179Z","end":"2026-03-18T05:50:24.308345Z","steps":["trace[503137628] 'process raft request' (duration: 221.277874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.308465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.709138ms","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-03-18T05:50:24.308453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.196276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-18T05:50:24.308444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.855109ms","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-03-18T05:50:24.308616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.212272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:24.308661Z","caller":"traceutil/trace.go:171","msg":"trace[1636878253] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:7949; }","duration":"149.279944ms","start":"2026-03-18T05:50:24.159369Z","end":"2026-03-18T05:50:24.308649Z","steps":["trace[1636878253] 'agreement among raft nodes before linearized reading' (duration: 149.224463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.308674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.50726ms","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-03-18T05:50:24.308715Z","caller":"traceutil/trace.go:171","msg":"trace[1867227035] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7949; }","duration":"487.596912ms","start":"2026-03-18T05:50:23.821108Z","end":"2026-03-18T05:50:24.308705Z","steps":["trace[1867227035] 'agreement among raft nodes before linearized reading' (duration: 487.5016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.308761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.821089Z","time spent":"487.662373ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T05:50:24.308732Z","caller":"traceutil/trace.go:171","msg":"trace[1306883810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7949; }","duration":"524.170185ms","start":"2026-03-18T05:50:23.784457Z","end":"2026-03-18T05:50:24.308627Z","steps":["trace[1306883810] 'agreement among raft nodes before linearized reading' (duration: 523.396107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.308894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.784446Z","time spent":"524.432072ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-18T05:50:24.308569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.426083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:50:24.309015Z","caller":"traceutil/trace.go:171","msg":"trace[2123388703] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:7949; }","duration":"106.886523ms","start":"2026-03-18T05:50:24.202115Z","end":"2026-03-18T05:50:24.309002Z","steps":["trace[2123388703] 'agreement among raft nodes before linearized reading' (duration: 106.409272ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:24.308571Z","caller":"traceutil/trace.go:171","msg":"trace[1604801683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7949; }","duration":"524.356871ms","start":"2026-03-18T05:50:23.784189Z","end":"2026-03-18T05:50:24.308546Z","steps":["trace[1604801683] 'agreement among raft nodes before linearized reading' (duration: 524.049193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.309155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.784181Z","time spent":"524.958774ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T05:50:24.308507Z","caller":"traceutil/trace.go:171","msg":"trace[1723030298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7949; }","duration":"524.749859ms","start":"2026-03-18T05:50:23.783747Z","end":"2026-03-18T05:50:24.308497Z","steps":["trace[1723030298] 'agreement among raft nodes before linearized reading' (duration: 524.587676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:24.30926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:23.783702Z","time spent":"525.551919ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-18T05:50:24.315149Z","caller":"traceutil/trace.go:171","msg":"trace[547074502] transaction","detail":"{read_only:false; response_revision:7950; number_of_response:1; }","duration":"101.275182ms","start":"2026-03-18T05:50:24.213853Z","end":"2026-03-18T05:50:24.315128Z","steps":["trace[547074502] 'process raft request' (duration: 101.128229ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:33.464638Z","caller":"traceutil/trace.go:171","msg":"trace[1565866081] transaction","detail":"{read_only:false; response_revision:8034; number_of_response:1; }","duration":"396.162905ms","start":"2026-03-18T05:50:33.068429Z","end":"2026-03-18T05:50:33.464592Z","steps":["trace[1565866081] 'process raft request' (duration: 395.893498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:33.464881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:50:33.068397Z","time spent":"396.349048ms","remote":"127.0.0.1:41106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:50:33.602175Z","caller":"traceutil/trace.go:171","msg":"trace[1287200910] linearizableReadLoop","detail":"{readStateIndex:8334; appliedIndex:8333; }","duration":"137.626469ms","start":"2026-03-18T05:50:33.464521Z","end":"2026-03-18T05:50:33.602148Z","steps":["trace[1287200910] 'read index received' (duration: 60.528568ms)","trace[1287200910] 'applied index is now lower than readState.Index' (duration: 77.096561ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:33.602259Z","caller":"traceutil/trace.go:171","msg":"trace[1128994274] transaction","detail":"{read_only:false; response_revision:8035; number_of_response:1; }","duration":"209.195599ms","start":"2026-03-18T05:50:33.393013Z","end":"2026-03-18T05:50:33.602209Z","steps":["trace[1128994274] 'process raft request' (duration: 132.099389ms)","trace[1128994274] 'compare' (duration: 76.884556ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:50:33.602537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.105696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"warn","ts":"2026-03-18T05:50:33.602514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.562958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:50:33.602677Z","caller":"traceutil/trace.go:171","msg":"trace[1110893460] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8035; }","duration":"170.745172ms","start":"2026-03-18T05:50:33.431909Z","end":"2026-03-18T05:50:33.602654Z","steps":["trace[1110893460] 'agreement among raft nodes before linearized reading' (duration: 170.462795ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:33.602611Z","caller":"traceutil/trace.go:171","msg":"trace[335637415] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8035; }","duration":"181.337352ms","start":"2026-03-18T05:50:33.421235Z","end":"2026-03-18T05:50:33.602573Z","steps":["trace[335637415] 'agreement among raft nodes before linearized reading' (duration: 181.009294ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:50:33.753405Z","caller":"traceutil/trace.go:171","msg":"trace[797130031] transaction","detail":"{read_only:false; response_revision:8036; number_of_response:1; }","duration":"137.712211ms","start":"2026-03-18T05:50:33.615669Z","end":"2026-03-18T05:50:33.753381Z","steps":["trace[797130031] 'process raft request' (duration: 102.699914ms)","trace[797130031] 'compare' (duration: 34.821042ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:33.982627Z","caller":"traceutil/trace.go:171","msg":"trace[766839633] linearizableReadLoop","detail":"{readStateIndex:8338; appliedIndex:8337; }","duration":"121.413977ms","start":"2026-03-18T05:50:33.861184Z","end":"2026-03-18T05:50:33.982598Z","steps":["trace[766839633] 'read index received' (duration: 121.162811ms)","trace[766839633] 'applied index is now lower than readState.Index' (duration: 250.276µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:50:33.982798Z","caller":"traceutil/trace.go:171","msg":"trace[990512431] transaction","detail":"{read_only:false; response_revision:8039; number_of_response:1; }","duration":"127.102472ms","start":"2026-03-18T05:50:33.855658Z","end":"2026-03-18T05:50:33.982761Z","steps":["trace[990512431] 'process raft request' (duration: 126.751364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:50:33.983106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.866807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-03-18T05:50:33.983192Z","caller":"traceutil/trace.go:171","msg":"trace[1335418086] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8039; }","duration":"122.025691ms","start":"2026-03-18T05:50:33.861149Z","end":"2026-03-18T05:50:33.983174Z","steps":["trace[1335418086] 'agreement among raft nodes before linearized reading' (duration: 121.652443ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:50.317037Z","caller":"traceutil/trace.go:171","msg":"trace[1731900833] linearizableReadLoop","detail":"{readStateIndex:9063; appliedIndex:9062; }","duration":"198.904639ms","start":"2026-03-18T05:51:50.118105Z","end":"2026-03-18T05:51:50.31701Z","steps":["trace[1731900833] 'read index received' (duration: 198.780105ms)","trace[1731900833] 'applied index is now lower than readState.Index' (duration: 123.203µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:51:50.31717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.047812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:51:50.317203Z","caller":"traceutil/trace.go:171","msg":"trace[1671046405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8745; }","duration":"199.122105ms","start":"2026-03-18T05:51:50.11807Z","end":"2026-03-18T05:51:50.317192Z","steps":["trace[1671046405] 'agreement among raft nodes before linearized reading' (duration: 199.043603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:50.656896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.105275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:51:50.657142Z","caller":"traceutil/trace.go:171","msg":"trace[1111897475] transaction","detail":"{read_only:false; response_revision:8746; number_of_response:1; }","duration":"338.83489ms","start":"2026-03-18T05:51:50.318279Z","end":"2026-03-18T05:51:50.657114Z","steps":["trace[1111897475] 'process raft request' (duration: 213.341676ms)","trace[1111897475] 'compare' (duration: 124.87134ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:51:50.65725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:50.318256Z","time spent":"338.944093ms","remote":"127.0.0.1:42096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:51:50.657292Z","caller":"traceutil/trace.go:171","msg":"trace[164082003] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"281.681515ms","start":"2026-03-18T05:51:50.375595Z","end":"2026-03-18T05:51:50.657277Z","steps":["trace[164082003] 'process raft request' (duration: 281.489601ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:50.746109Z","caller":"traceutil/trace.go:171","msg":"trace[532389465] transaction","detail":"{read_only:false; response_revision:8748; number_of_response:1; }","duration":"174.93363ms","start":"2026-03-18T05:51:50.571146Z","end":"2026-03-18T05:51:50.746079Z","steps":["trace[532389465] 'process raft request' (duration: 174.845858ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:50.81945Z","caller":"traceutil/trace.go:171","msg":"trace[301390861] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"178.914532ms","start":"2026-03-18T05:51:50.64051Z","end":"2026-03-18T05:51:50.819424Z","steps":["trace[301390861] 'process raft request' (duration: 119.206647ms)","trace[301390861] 'compare' (duration: 59.536011ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:51:51.162565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.367164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:51:51.162662Z","caller":"traceutil/trace.go:171","msg":"trace[367724682] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"279.928472ms","start":"2026-03-18T05:51:50.882717Z","end":"2026-03-18T05:51:51.162646Z","steps":["trace[367724682] 'process raft request' (duration: 135.392415ms)","trace[367724682] 'compare' (duration: 144.21904ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:51:51.199292Z","caller":"traceutil/trace.go:171","msg":"trace[351532703] linearizableReadLoop","detail":"{readStateIndex:9069; appliedIndex:9067; }","duration":"311.794277ms","start":"2026-03-18T05:51:50.887476Z","end":"2026-03-18T05:51:51.19927Z","steps":["trace[351532703] 'read index received' (duration: 130.723456ms)","trace[351532703] 'applied index is now lower than readState.Index' (duration: 181.069931ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:51:51.199383Z","caller":"traceutil/trace.go:171","msg":"trace[2017319419] transaction","detail":"{read_only:false; response_revision:8751; number_of_response:1; }","duration":"314.50951ms","start":"2026-03-18T05:51:50.884847Z","end":"2026-03-18T05:51:51.199356Z","steps":["trace[2017319419] 'process raft request' (duration: 314.298885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.19959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.050904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T05:51:51.199654Z","caller":"traceutil/trace.go:171","msg":"trace[903202319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8751; }","duration":"312.205758ms","start":"2026-03-18T05:51:50.887436Z","end":"2026-03-18T05:51:51.199642Z","steps":["trace[903202319] 'agreement among raft nodes before linearized reading' (duration: 311.94189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.19966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:50.884829Z","time spent":"314.614204ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T05:51:51.199692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:50.887425Z","time spent":"312.255858ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T05:51:51.574692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.597255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T05:51:51.574999Z","caller":"traceutil/trace.go:171","msg":"trace[543219289] linearizableReadLoop","detail":"{readStateIndex:9070; appliedIndex:9069; }","duration":"375.626789ms","start":"2026-03-18T05:51:51.199352Z","end":"2026-03-18T05:51:51.574979Z","steps":["trace[543219289] 'read index received' (duration: 71.556523ms)","trace[543219289] 'applied index is now lower than readState.Index' (duration: 304.068466ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:51:51.575095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.74137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T05:51:51.575091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.13996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:51:51.575115Z","caller":"traceutil/trace.go:171","msg":"trace[637253859] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"405.20982ms","start":"2026-03-18T05:51:51.169861Z","end":"2026-03-18T05:51:51.575071Z","steps":["trace[637253859] 'process raft request' (duration: 101.082512ms)","trace[637253859] 'compare' (duration: 303.325549ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:51:51.575192Z","caller":"traceutil/trace.go:171","msg":"trace[573434120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8752; }","duration":"456.289714ms","start":"2026-03-18T05:51:51.118883Z","end":"2026-03-18T05:51:51.575172Z","steps":["trace[573434120] 'agreement among raft nodes before linearized reading' (duration: 456.10851ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.575254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:51.118862Z","time spent":"456.378835ms","remote":"127.0.0.1:42028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T05:51:51.575204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.062397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-03-18T05:51:51.575259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.787163ms","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-03-18T05:51:51.575297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:51.169833Z","time spent":"405.363364ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:51:51.575187Z","caller":"traceutil/trace.go:171","msg":"trace[736431087] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8752; }","duration":"416.888493ms","start":"2026-03-18T05:51:51.158273Z","end":"2026-03-18T05:51:51.575162Z","steps":["trace[736431087] 'agreement among raft nodes before linearized reading' (duration: 416.7473ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:51.575315Z","caller":"traceutil/trace.go:171","msg":"trace[805044663] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:8752; }","duration":"500.229541ms","start":"2026-03-18T05:51:51.075074Z","end":"2026-03-18T05:51:51.575303Z","steps":["trace[805044663] 'agreement among raft nodes before linearized reading' (duration: 499.987015ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:51.575346Z","caller":"traceutil/trace.go:171","msg":"trace[1589807187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8752; }","duration":"663.906646ms","start":"2026-03-18T05:51:50.91142Z","end":"2026-03-18T05:51:51.575327Z","steps":["trace[1589807187] 'agreement among raft nodes before linearized reading' (duration: 663.659621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.575353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.656247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-18T05:51:51.575385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:51.075051Z","time spent":"500.323053ms","remote":"127.0.0.1:41040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-hj96p\" "} {"level":"warn","ts":"2026-03-18T05:51:51.57539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:50.911411Z","time spent":"663.968507ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T05:51:51.575393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:51.158252Z","time spent":"417.119728ms","remote":"127.0.0.1:52528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-18T05:51:51.575423Z","caller":"traceutil/trace.go:171","msg":"trace[1018097988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8752; }","duration":"674.75663ms","start":"2026-03-18T05:51:50.90065Z","end":"2026-03-18T05:51:51.575407Z","steps":["trace[1018097988] 'agreement among raft nodes before linearized reading' (duration: 674.484644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.57547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:50.90064Z","time spent":"674.817311ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T05:51:51.770652Z","caller":"traceutil/trace.go:171","msg":"trace[1284169199] linearizableReadLoop","detail":"{readStateIndex:9071; appliedIndex:9070; }","duration":"195.596721ms","start":"2026-03-18T05:51:51.575029Z","end":"2026-03-18T05:51:51.770626Z","steps":["trace[1284169199] 'read index received' (duration: 100.782216ms)","trace[1284169199] 'applied index is now lower than readState.Index' (duration: 94.813335ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T05:51:51.770725Z","caller":"traceutil/trace.go:171","msg":"trace[1285571085] transaction","detail":"{read_only:false; response_revision:8753; number_of_response:1; }","duration":"566.98058ms","start":"2026-03-18T05:51:51.203711Z","end":"2026-03-18T05:51:51.770692Z","steps":["trace[1285571085] 'process raft request' (duration: 472.083574ms)","trace[1285571085] 'compare' (duration: 94.663591ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T05:51:51.770851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.503404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:51:51.770896Z","caller":"traceutil/trace.go:171","msg":"trace[288145719] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:8753; }","duration":"529.595707ms","start":"2026-03-18T05:51:51.241293Z","end":"2026-03-18T05:51:51.770888Z","steps":["trace[288145719] 'agreement among raft nodes before linearized reading' (duration: 529.465604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.770883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.100094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T05:51:51.770983Z","caller":"traceutil/trace.go:171","msg":"trace[794048577] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8753; }","duration":"235.270949ms","start":"2026-03-18T05:51:51.53569Z","end":"2026-03-18T05:51:51.770961Z","steps":["trace[794048577] 'agreement among raft nodes before linearized reading' (duration: 235.024992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.770929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:51.241275Z","time spent":"529.646359ms","remote":"127.0.0.1:52518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-03-18T05:51:51.770858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T05:51:51.203696Z","time spent":"567.091543ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T05:51:51.833454Z","caller":"traceutil/trace.go:171","msg":"trace[177146497] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"252.43973ms","start":"2026-03-18T05:51:51.580973Z","end":"2026-03-18T05:51:51.833413Z","steps":["trace[177146497] 'process raft request' (duration: 252.122253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.833553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.515545ms","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-03-18T05:51:51.833596Z","caller":"traceutil/trace.go:171","msg":"trace[1660579889] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"253.489925ms","start":"2026-03-18T05:51:51.580095Z","end":"2026-03-18T05:51:51.833585Z","steps":["trace[1660579889] 'process raft request' (duration: 252.866081ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:51:51.833615Z","caller":"traceutil/trace.go:171","msg":"trace[1927826845] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8755; }","duration":"241.604236ms","start":"2026-03-18T05:51:51.591993Z","end":"2026-03-18T05:51:51.833597Z","steps":["trace[1927826845] 'agreement among raft nodes before linearized reading' (duration: 241.472113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.833654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.181821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T05:51:51.83377Z","caller":"traceutil/trace.go:171","msg":"trace[1115028049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8755; }","duration":"257.346175ms","start":"2026-03-18T05:51:51.576399Z","end":"2026-03-18T05:51:51.833745Z","steps":["trace[1115028049] 'agreement among raft nodes before linearized reading' (duration: 257.14923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T05:51:51.834094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.939965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291515"} {"level":"info","ts":"2026-03-18T05:51:51.834179Z","caller":"traceutil/trace.go:171","msg":"trace[1942355367] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:8755; }","duration":"254.081908ms","start":"2026-03-18T05:51:51.580079Z","end":"2026-03-18T05:51:51.834161Z","steps":["trace[1942355367] 'agreement among raft nodes before linearized reading' (duration: 253.124656ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T05:54:17.167201Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"14f8f2dfdaccebdb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T05:54:17.172695Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-18T05:54:17.172788Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-18T05:54:58.715093Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7778} {"level":"info","ts":"2026-03-18T05:54:58.78172Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7778,"took":"64.418831ms","hash":2909475451,"current-db-size-bytes":42369024,"current-db-size":"42 MB","current-db-size-in-use-bytes":25374720,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-18T05:54:58.78179Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2909475451,"revision":7778,"compact-revision":5759} {"level":"info","ts":"2026-03-18T05:59:58.722281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9841} {"level":"info","ts":"2026-03-18T05:59:58.800762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9841,"took":"76.044352ms","hash":4176827195,"current-db-size-bytes":42369024,"current-db-size":"42 MB","current-db-size-in-use-bytes":18079744,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-18T05:59:58.800862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4176827195,"revision":9841,"compact-revision":7778} {"level":"info","ts":"2026-03-18T06:02:05.838508Z","caller":"traceutil/trace.go:171","msg":"trace[518876388] linearizableReadLoop","detail":"{readStateIndex:12455; appliedIndex:12454; }","duration":"366.233995ms","start":"2026-03-18T06:02:05.472246Z","end":"2026-03-18T06:02:05.83848Z","steps":["trace[518876388] 'read index received' (duration: 366.053401ms)","trace[518876388] 'applied index is now lower than readState.Index' (duration: 179.314µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T06:02:05.838604Z","caller":"traceutil/trace.go:171","msg":"trace[102081017] transaction","detail":"{read_only:false; response_revision:12001; number_of_response:1; }","duration":"372.794471ms","start":"2026-03-18T06:02:05.46574Z","end":"2026-03-18T06:02:05.838534Z","steps":["trace[102081017] 'process raft request' (duration: 372.580536ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T06:02:05.838768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.460276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/loki-gateway\" ","response":"range_response_count:1 size:4680"} {"level":"warn","ts":"2026-03-18T06:02:05.8388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T06:02:05.465706Z","time spent":"372.997056ms","remote":"127.0.0.1:41106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5112,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T06:02:05.838811Z","caller":"traceutil/trace.go:171","msg":"trace[948685727] range","detail":"{range_begin:/registry/deployments/monitoring/loki-gateway; range_end:; response_count:1; response_revision:12001; }","duration":"299.560608ms","start":"2026-03-18T06:02:05.53924Z","end":"2026-03-18T06:02:05.8388Z","steps":["trace[948685727] 'agreement among raft nodes before linearized reading' (duration: 299.404304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T06:02:05.838902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.594744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-operator\" ","response":"range_response_count:1 size:5445"} {"level":"info","ts":"2026-03-18T06:02:05.839022Z","caller":"traceutil/trace.go:171","msg":"trace[2010551589] range","detail":"{range_begin:/registry/deployments/monitoring/kube-prometheus-stack-operator; range_end:; response_count:1; response_revision:12001; }","duration":"366.782358ms","start":"2026-03-18T06:02:05.472219Z","end":"2026-03-18T06:02:05.839001Z","steps":["trace[2010551589] 'agreement among raft nodes before linearized reading' (duration: 366.418039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T06:02:05.839105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T06:02:05.472176Z","time spent":"366.916151ms","remote":"127.0.0.1:41306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":5467,"request content":"key:\"/registry/deployments/monitoring/kube-prometheus-stack-operator\" "} {"level":"info","ts":"2026-03-18T06:02:06.614464Z","caller":"traceutil/trace.go:171","msg":"trace[1533319730] linearizableReadLoop","detail":"{readStateIndex:12456; appliedIndex:12455; }","duration":"255.810718ms","start":"2026-03-18T06:02:06.358627Z","end":"2026-03-18T06:02:06.614438Z","steps":["trace[1533319730] 'read index received' (duration: 255.662115ms)","trace[1533319730] 'applied index is now lower than readState.Index' (duration: 147.113µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T06:02:06.615047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.550111ms","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-03-18T06:02:06.615092Z","caller":"traceutil/trace.go:171","msg":"trace[1856406532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12002; }","duration":"255.632323ms","start":"2026-03-18T06:02:06.359449Z","end":"2026-03-18T06:02:06.615081Z","steps":["trace[1856406532] 'agreement among raft nodes before linearized reading' (duration: 255.442349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T06:02:06.615213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.539405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/prometheus-pushgateway\" ","response":"range_response_count:1 size:4132"} {"level":"warn","ts":"2026-03-18T06:02:06.615314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.769499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/monitoring/goldpinger\" ","response":"range_response_count:1 size:932"} {"level":"info","ts":"2026-03-18T06:02:06.615337Z","caller":"traceutil/trace.go:171","msg":"trace[860701088] range","detail":"{range_begin:/registry/services/endpoints/monitoring/goldpinger; range_end:; response_count:1; response_revision:12002; }","duration":"132.832071ms","start":"2026-03-18T06:02:06.482498Z","end":"2026-03-18T06:02:06.61533Z","steps":["trace[860701088] 'agreement among raft nodes before linearized reading' (duration: 132.758779ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T06:02:06.615341Z","caller":"traceutil/trace.go:171","msg":"trace[1145245537] range","detail":"{range_begin:/registry/deployments/monitoring/prometheus-pushgateway; range_end:; response_count:1; response_revision:12002; }","duration":"256.707779ms","start":"2026-03-18T06:02:06.358607Z","end":"2026-03-18T06:02:06.615314Z","steps":["trace[1145245537] 'agreement among raft nodes before linearized reading' (duration: 256.33653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T06:02:06.615502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.34103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-18T06:02:06.615528Z","caller":"traceutil/trace.go:171","msg":"trace[35142939] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:12002; }","duration":"177.404201ms","start":"2026-03-18T06:02:06.438118Z","end":"2026-03-18T06:02:06.615522Z","steps":["trace[35142939] 'agreement among raft nodes before linearized reading' (duration: 177.33286ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T06:02:06.615741Z","caller":"traceutil/trace.go:171","msg":"trace[4096660] transaction","detail":"{read_only:false; response_revision:12002; number_of_response:1; }","duration":"257.158389ms","start":"2026-03-18T06:02:06.358563Z","end":"2026-03-18T06:02:06.615722Z","steps":["trace[4096660] 'process raft request' (duration: 255.679104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T06:02:37.09124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.071559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T06:02:37.091616Z","caller":"traceutil/trace.go:171","msg":"trace[6905412] transaction","detail":"{read_only:false; response_revision:12143; number_of_response:1; }","duration":"318.456338ms","start":"2026-03-18T06:02:36.773055Z","end":"2026-03-18T06:02:37.091511Z","steps":["trace[6905412] 'process raft request' (duration: 157.945139ms)","trace[6905412] 'compare' (duration: 159.893175ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T06:02:37.091744Z","caller":"traceutil/trace.go:171","msg":"trace[1931371880] linearizableReadLoop","detail":"{readStateIndex:12603; appliedIndex:12602; }","duration":"253.087432ms","start":"2026-03-18T06:02:36.838615Z","end":"2026-03-18T06:02:37.091702Z","steps":["trace[1931371880] 'read index received' (duration: 92.294616ms)","trace[1931371880] 'applied index is now lower than readState.Index' (duration: 160.786156ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T06:02:37.09184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T06:02:36.773036Z","time spent":"318.685173ms","remote":"127.0.0.1:41138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T06:02:37.092041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.41495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hj96p\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-18T06:02:37.092149Z","caller":"traceutil/trace.go:171","msg":"trace[2124885262] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hj96p; range_end:; response_count:1; response_revision:12144; }","duration":"253.597174ms","start":"2026-03-18T06:02:36.838523Z","end":"2026-03-18T06:02:37.09212Z","steps":["trace[2124885262] 'agreement among raft nodes before linearized reading' (duration: 253.320567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T06:02:37.092167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.164946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-fernet-setup\" ","response":"range_response_count:1 size:735"} {"level":"warn","ts":"2026-03-18T06:02:37.09224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.716286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/keystone-fernet-rotate\" ","response":"range_response_count:1 size:739"} {"level":"info","ts":"2026-03-18T06:02:37.092303Z","caller":"traceutil/trace.go:171","msg":"trace[1449154749] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-fernet-setup; range_end:; response_count:1; response_revision:12144; }","duration":"222.35788ms","start":"2026-03-18T06:02:36.86992Z","end":"2026-03-18T06:02:37.092277Z","steps":["trace[1449154749] 'agreement among raft nodes before linearized reading' (duration: 222.028362ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T06:02:37.092368Z","caller":"traceutil/trace.go:171","msg":"trace[123609602] range","detail":"{range_begin:/registry/rolebindings/openstack/keystone-fernet-rotate; range_end:; response_count:1; response_revision:12144; }","duration":"245.876891ms","start":"2026-03-18T06:02:36.846459Z","end":"2026-03-18T06:02:37.092336Z","steps":["trace[123609602] 'agreement among raft nodes before linearized reading' (duration: 245.470471ms)"],"step_count":1}