{"level":"warn","ts":"2026-03-01T21:11:26.85114Z","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-01T21:11:26.851238Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-01T21:11:26.85133Z","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-01T21:11:26.85134Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-01T21:11:26.851356Z","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-01T21:11:26.851999Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-03-01T21:11:26.852075Z","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.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-01T21:11:26.856618Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.332179ms"} {"level":"info","ts":"2026-03-01T21:11:26.865184Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-03-01T21:11:26.865303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T21:11:26.865331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-03-01T21:11:26.865346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T21:11:26.865359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-03-01T21:11:26.865401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-03-01T21:11:26.872405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T21:11:26.874729Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T21:11:26.876762Z","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-01T21:11:26.880166Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T21:11:26.882234Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T21:11:26.88409Z","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-01T21:11:26.884325Z","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-01T21:11:26.884363Z","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-01T21:11:26.885079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-03-01T21:11:26.885312Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-03-01T21:11:26.886294Z","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-01T21:11:26.886488Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-01T21:11:26.88655Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-03-01T21:11:26.886592Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T21:11:26.886642Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T21:11:27.069293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T21:11:27.069385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T21:11:27.069416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-03-01T21:11:27.06944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-03-01T21:11:27.069453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-01T21:11:27.069473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-03-01T21:11:27.069497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-03-01T21:11:27.07757Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T21:11:27.077624Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T21:11:27.077713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T21:11:27.077857Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T21:11:27.077959Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T21:11:27.077921Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:11:27.07879Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:11:27.078981Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:11:27.079027Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T21:11:27.082022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T21:11:27.082777Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-03-01T21:11:37.754777Z","caller":"traceutil/trace.go:171","msg":"trace[1038152153] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:318; }","duration":"101.645913ms","start":"2026-03-01T21:11:37.65309Z","end":"2026-03-01T21:11:37.754736Z","steps":["trace[1038152153] 'read index received' (duration: 99.473659ms)","trace[1038152153] 'applied index is now lower than readState.Index' (duration: 2.170974ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:11:37.754939Z","caller":"traceutil/trace.go:171","msg":"trace[575104723] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"105.222026ms","start":"2026-03-01T21:11:37.649685Z","end":"2026-03-01T21:11:37.754907Z","steps":["trace[575104723] 'process raft request' (duration: 102.884069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:11:37.755295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.141704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-01T21:11:37.755388Z","caller":"traceutil/trace.go:171","msg":"trace[1855301306] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:307; }","duration":"102.333588ms","start":"2026-03-01T21:11:37.653037Z","end":"2026-03-01T21:11:37.75537Z","steps":["trace[1855301306] 'agreement among raft nodes before linearized reading' (duration: 102.126614ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:11:38.32381Z","caller":"traceutil/trace.go:171","msg":"trace[1205727544] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"131.348725ms","start":"2026-03-01T21:11:38.19244Z","end":"2026-03-01T21:11:38.323788Z","steps":["trace[1205727544] 'process raft request' (duration: 131.008537ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:11:38.323838Z","caller":"traceutil/trace.go:171","msg":"trace[855833237] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"136.679345ms","start":"2026-03-01T21:11:38.187145Z","end":"2026-03-01T21:11:38.323824Z","steps":["trace[855833237] 'process raft request' (duration: 136.252116ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:11:38.323812Z","caller":"traceutil/trace.go:171","msg":"trace[115874206] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"136.683666ms","start":"2026-03-01T21:11:38.187095Z","end":"2026-03-01T21:11:38.323779Z","steps":["trace[115874206] 'process raft request' (duration: 79.64615ms)","trace[115874206] 'compare' (duration: 56.487723ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:11:45.730308Z","caller":"traceutil/trace.go:171","msg":"trace[1979192136] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"356.724426ms","start":"2026-03-01T21:11:45.373555Z","end":"2026-03-01T21:11:45.73028Z","steps":["trace[1979192136] 'read index received' (duration: 356.478411ms)","trace[1979192136] 'applied index is now lower than readState.Index' (duration: 244.955µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:11:45.730383Z","caller":"traceutil/trace.go:171","msg":"trace[2103368001] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"390.520022ms","start":"2026-03-01T21:11:45.339828Z","end":"2026-03-01T21:11:45.730348Z","steps":["trace[2103368001] 'process raft request' (duration: 390.311678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:11:45.730532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.972152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:11:45.730588Z","caller":"traceutil/trace.go:171","msg":"trace[911035804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"357.091275ms","start":"2026-03-01T21:11:45.373484Z","end":"2026-03-01T21:11:45.730575Z","steps":["trace[911035804] 'agreement among raft nodes before linearized reading' (duration: 356.954082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:11:45.730644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:11:45.373463Z","time spent":"357.171296ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T21:11:45.731237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:11:45.339808Z","time spent":"390.668836ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:12:06.114689Z","caller":"traceutil/trace.go:171","msg":"trace[13654356] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"212.475506ms","start":"2026-03-01T21:12:05.902186Z","end":"2026-03-01T21:12:06.114662Z","steps":["trace[13654356] 'read index received' (duration: 212.386575ms)","trace[13654356] 'applied index is now lower than readState.Index' (duration: 87.821µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:12:06.114761Z","caller":"traceutil/trace.go:171","msg":"trace[100548382] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"619.939386ms","start":"2026-03-01T21:12:05.494795Z","end":"2026-03-01T21:12:06.114735Z","steps":["trace[100548382] 'process raft request' (duration: 619.696471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:06.114896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.704491ms","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-01T21:12:06.114916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:05.494757Z","time spent":"620.061618ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:12:06.11496Z","caller":"traceutil/trace.go:171","msg":"trace[851390427] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:513; }","duration":"212.822023ms","start":"2026-03-01T21:12:05.902123Z","end":"2026-03-01T21:12:06.114945Z","steps":["trace[851390427] 'agreement among raft nodes before linearized reading' (duration: 212.68436ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:06.321493Z","caller":"traceutil/trace.go:171","msg":"trace[1030373717] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"206.67993ms","start":"2026-03-01T21:12:06.114785Z","end":"2026-03-01T21:12:06.321465Z","steps":["trace[1030373717] 'read index received' (duration: 178.472532ms)","trace[1030373717] 'applied index is now lower than readState.Index' (duration: 28.205618ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:12:06.321712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.144089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:12:06.321765Z","caller":"traceutil/trace.go:171","msg":"trace[1809733044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"357.246381ms","start":"2026-03-01T21:12:05.964507Z","end":"2026-03-01T21:12:06.321753Z","steps":["trace[1809733044] 'agreement among raft nodes before linearized reading' (duration: 357.031766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:06.321805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:05.964497Z","time spent":"357.300911ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T21:12:06.321862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.458289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-01T21:12:06.32189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.792542ms","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-01T21:12:06.322071Z","caller":"traceutil/trace.go:171","msg":"trace[1577201103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"374.040648ms","start":"2026-03-01T21:12:05.947963Z","end":"2026-03-01T21:12:06.322004Z","steps":["trace[1577201103] 'agreement among raft nodes before linearized reading' (duration: 373.617569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:06.322118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:12:05.947949Z","time spent":"374.16075ms","remote":"127.0.0.1:38590","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-01T21:12:06.321956Z","caller":"traceutil/trace.go:171","msg":"trace[1389382292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"163.606722ms","start":"2026-03-01T21:12:06.158322Z","end":"2026-03-01T21:12:06.321928Z","steps":["trace[1389382292] 'agreement among raft nodes before linearized reading' (duration: 163.290366ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:07.108301Z","caller":"traceutil/trace.go:171","msg":"trace[355033934] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"100.027473ms","start":"2026-03-01T21:12:07.008239Z","end":"2026-03-01T21:12:07.108267Z","steps":["trace[355033934] 'process raft request' (duration: 99.823779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:07.361322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.662512ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:12:07.361494Z","caller":"traceutil/trace.go:171","msg":"trace[376543003] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:517; }","duration":"152.852776ms","start":"2026-03-01T21:12:07.208612Z","end":"2026-03-01T21:12:07.361465Z","steps":["trace[376543003] 'range keys from in-memory index tree' (duration: 152.635501ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:08.963004Z","caller":"traceutil/trace.go:171","msg":"trace[1660071778] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"205.092367ms","start":"2026-03-01T21:12:08.757887Z","end":"2026-03-01T21:12:08.96298Z","steps":["trace[1660071778] 'read index received' (duration: 204.639389ms)","trace[1660071778] 'applied index is now lower than readState.Index' (duration: 452.168µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:12:08.963076Z","caller":"traceutil/trace.go:171","msg":"trace[1090205299] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"245.328868ms","start":"2026-03-01T21:12:08.717705Z","end":"2026-03-01T21:12:08.963034Z","steps":["trace[1090205299] 'process raft request' (duration: 244.975801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:08.96318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.230389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:12:08.963221Z","caller":"traceutil/trace.go:171","msg":"trace[1037246818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:643; }","duration":"192.317651ms","start":"2026-03-01T21:12:08.770891Z","end":"2026-03-01T21:12:08.963209Z","steps":["trace[1037246818] 'agreement among raft nodes before linearized reading' (duration: 192.236899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:08.963293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.385853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:12:08.963472Z","caller":"traceutil/trace.go:171","msg":"trace[1351109772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"205.599487ms","start":"2026-03-01T21:12:08.757818Z","end":"2026-03-01T21:12:08.963418Z","steps":["trace[1351109772] 'agreement among raft nodes before linearized reading' (duration: 205.305831ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:13.643385Z","caller":"traceutil/trace.go:171","msg":"trace[329238041] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"147.205133ms","start":"2026-03-01T21:12:13.49616Z","end":"2026-03-01T21:12:13.643365Z","steps":["trace[329238041] 'process raft request' (duration: 110.234968ms)","trace[329238041] 'compare' (duration: 36.701248ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:12:13.663645Z","caller":"traceutil/trace.go:171","msg":"trace[325587292] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"151.481609ms","start":"2026-03-01T21:12:13.512101Z","end":"2026-03-01T21:12:13.663582Z","steps":["trace[325587292] 'process raft request' (duration: 151.266454ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:27.566903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.874384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-01T21:12:27.566932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.341542ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:12:27.566991Z","caller":"traceutil/trace.go:171","msg":"trace[1678453712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:891; }","duration":"211.994996ms","start":"2026-03-01T21:12:27.35498Z","end":"2026-03-01T21:12:27.566975Z","steps":["trace[1678453712] 'range keys from in-memory index tree' (duration: 211.745721ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:27.56702Z","caller":"traceutil/trace.go:171","msg":"trace[437421906] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:891; }","duration":"357.428324ms","start":"2026-03-01T21:12:27.209578Z","end":"2026-03-01T21:12:27.567007Z","steps":["trace[437421906] 'range keys from in-memory index tree' (duration: 357.328962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:27.566907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.370784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-01T21:12:27.56749Z","caller":"traceutil/trace.go:171","msg":"trace[1334377268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:891; }","duration":"294.006577ms","start":"2026-03-01T21:12:27.273471Z","end":"2026-03-01T21:12:27.567478Z","steps":["trace[1334377268] 'range keys from in-memory index tree' (duration: 293.203671ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:52.774495Z","caller":"traceutil/trace.go:171","msg":"trace[2006826249] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"124.946854ms","start":"2026-03-01T21:12:52.649527Z","end":"2026-03-01T21:12:52.774474Z","steps":["trace[2006826249] 'read index received' (duration: 124.74246ms)","trace[2006826249] 'applied index is now lower than readState.Index' (duration: 203.504µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:12:52.774721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.177298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:12:52.774823Z","caller":"traceutil/trace.go:171","msg":"trace[1126284411] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1056; }","duration":"125.31325ms","start":"2026-03-01T21:12:52.649485Z","end":"2026-03-01T21:12:52.774798Z","steps":["trace[1126284411] 'agreement among raft nodes before linearized reading' (duration: 125.151577ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:52.775386Z","caller":"traceutil/trace.go:171","msg":"trace[596776741] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"135.139759ms","start":"2026-03-01T21:12:52.639484Z","end":"2026-03-01T21:12:52.774624Z","steps":["trace[596776741] 'process raft request' (duration: 134.851533ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:12:58.933031Z","caller":"traceutil/trace.go:171","msg":"trace[720146270] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"130.312173ms","start":"2026-03-01T21:12:58.80269Z","end":"2026-03-01T21:12:58.933003Z","steps":["trace[720146270] 'read index received' (duration: 129.71814ms)","trace[720146270] 'applied index is now lower than readState.Index' (duration: 592.983µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:12:58.933146Z","caller":"traceutil/trace.go:171","msg":"trace[598025379] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"133.054659ms","start":"2026-03-01T21:12:58.800045Z","end":"2026-03-01T21:12:58.9331Z","steps":["trace[598025379] 'process raft request' (duration: 132.388375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:58.933423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.709401ms","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-01T21:12:58.933493Z","caller":"traceutil/trace.go:171","msg":"trace[1089472953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"130.829233ms","start":"2026-03-01T21:12:58.802642Z","end":"2026-03-01T21:12:58.933471Z","steps":["trace[1089472953] 'agreement among raft nodes before linearized reading' (duration: 130.467106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:58.933293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.161069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:12:58.933847Z","caller":"traceutil/trace.go:171","msg":"trace[2141605805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1173; }","duration":"129.649679ms","start":"2026-03-01T21:12:58.804105Z","end":"2026-03-01T21:12:58.933755Z","steps":["trace[2141605805] 'agreement among raft nodes before linearized reading' (duration: 129.045747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:12:58.933369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.294922ms","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-01T21:12:58.934067Z","caller":"traceutil/trace.go:171","msg":"trace[1625465290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"128.943835ms","start":"2026-03-01T21:12:58.805054Z","end":"2026-03-01T21:12:58.933998Z","steps":["trace[1625465290] 'agreement among raft nodes before linearized reading' (duration: 128.264201ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:10.857Z","caller":"traceutil/trace.go:171","msg":"trace[1239923754] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"135.169083ms","start":"2026-03-01T21:13:10.721802Z","end":"2026-03-01T21:13:10.856971Z","steps":["trace[1239923754] 'read index received' (duration: 135.064611ms)","trace[1239923754] 'applied index is now lower than readState.Index' (duration: 103.232µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:13:10.857056Z","caller":"traceutil/trace.go:171","msg":"trace[205575997] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"135.790444ms","start":"2026-03-01T21:13:10.72122Z","end":"2026-03-01T21:13:10.85701Z","steps":["trace[205575997] 'process raft request' (duration: 135.604501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:10.857275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.416038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:13:10.857323Z","caller":"traceutil/trace.go:171","msg":"trace[192573894] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com; range_end:; response_count:0; response_revision:1393; }","duration":"135.568801ms","start":"2026-03-01T21:13:10.72174Z","end":"2026-03-01T21:13:10.857309Z","steps":["trace[192573894] 'agreement among raft nodes before linearized reading' (duration: 135.377057ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:10.989761Z","caller":"traceutil/trace.go:171","msg":"trace[1930440058] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"132.643954ms","start":"2026-03-01T21:13:10.857083Z","end":"2026-03-01T21:13:10.989727Z","steps":["trace[1930440058] 'read index received' (duration: 132.519392ms)","trace[1930440058] 'applied index is now lower than readState.Index' (duration: 122.962µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:13:10.989823Z","caller":"traceutil/trace.go:171","msg":"trace[577248359] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"262.70764ms","start":"2026-03-01T21:13:10.727069Z","end":"2026-03-01T21:13:10.989777Z","steps":["trace[577248359] 'process raft request' (duration: 262.446095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:10.990067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.856849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-01T21:13:10.990116Z","caller":"traceutil/trace.go:171","msg":"trace[762910460] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:1394; }","duration":"161.983911ms","start":"2026-03-01T21:13:10.828121Z","end":"2026-03-01T21:13:10.990105Z","steps":["trace[762910460] 'agreement among raft nodes before linearized reading' (duration: 161.787377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:10.990069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.888175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:13:10.990233Z","caller":"traceutil/trace.go:171","msg":"trace[873783119] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com; range_end:; response_count:0; response_revision:1394; }","duration":"128.112129ms","start":"2026-03-01T21:13:10.862087Z","end":"2026-03-01T21:13:10.990199Z","steps":["trace[873783119] 'agreement among raft nodes before linearized reading' (duration: 127.854653ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:16.865029Z","caller":"traceutil/trace.go:171","msg":"trace[305481453] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"115.276265ms","start":"2026-03-01T21:13:16.749719Z","end":"2026-03-01T21:13:16.864996Z","steps":["trace[305481453] 'process raft request' (duration: 115.056921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.481786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644775672651153,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T21:13:24.507198Z","caller":"traceutil/trace.go:171","msg":"trace[1802051370] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"564.109887ms","start":"2026-03-01T21:13:23.943062Z","end":"2026-03-01T21:13:24.507172Z","steps":["trace[1802051370] 'process raft request' (duration: 563.911104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.507376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:23.943027Z","time spent":"564.25668ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:13:24.53599Z","caller":"traceutil/trace.go:171","msg":"trace[1958229449] linearizableReadLoop","detail":"{readStateIndex:1639; appliedIndex:1637; }","duration":"554.705268ms","start":"2026-03-01T21:13:23.981248Z","end":"2026-03-01T21:13:24.535953Z","steps":["trace[1958229449] 'read index received' (duration: 525.741389ms)","trace[1958229449] 'applied index is now lower than readState.Index' (duration: 28.962409ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:13:24.536084Z","caller":"traceutil/trace.go:171","msg":"trace[1724424466] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"522.695952ms","start":"2026-03-01T21:13:24.013349Z","end":"2026-03-01T21:13:24.536045Z","steps":["trace[1724424466] 'process raft request' (duration: 522.423367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.536274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:24.013317Z","time spent":"522.862225ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:24.536348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.896363ms","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-01T21:13:24.5364Z","caller":"traceutil/trace.go:171","msg":"trace[456112320] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1574; }","duration":"129.987645ms","start":"2026-03-01T21:13:24.406395Z","end":"2026-03-01T21:13:24.536382Z","steps":["trace[456112320] 'agreement among raft nodes before linearized reading' (duration: 129.812242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.536405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.151656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-01T21:13:24.536512Z","caller":"traceutil/trace.go:171","msg":"trace[364926403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1574; }","duration":"555.34465ms","start":"2026-03-01T21:13:23.981142Z","end":"2026-03-01T21:13:24.536487Z","steps":["trace[364926403] 'agreement among raft nodes before linearized reading' (duration: 555.002184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.536505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.402664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:13:24.536581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:23.981097Z","time spent":"555.473392ms","remote":"127.0.0.1:38590","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-01T21:13:24.536623Z","caller":"traceutil/trace.go:171","msg":"trace[470899532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1574; }","duration":"235.600148ms","start":"2026-03-01T21:13:24.301Z","end":"2026-03-01T21:13:24.5366Z","steps":["trace[470899532] 'agreement among raft nodes before linearized reading' (duration: 235.359053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.53709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.34271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T21:13:24.537147Z","caller":"traceutil/trace.go:171","msg":"trace[1236378122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1574; }","duration":"545.422242ms","start":"2026-03-01T21:13:23.99171Z","end":"2026-03-01T21:13:24.537132Z","steps":["trace[1236378122] 'agreement among raft nodes before linearized reading' (duration: 544.494435ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.537182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:23.991698Z","time spent":"545.475973ms","remote":"127.0.0.1:38590","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-01T21:13:24.925737Z","caller":"traceutil/trace.go:171","msg":"trace[2124882606] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"383.623074ms","start":"2026-03-01T21:13:24.542079Z","end":"2026-03-01T21:13:24.925702Z","steps":["trace[2124882606] 'process raft request' (duration: 383.450921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:24.925947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:24.542062Z","time spent":"383.775827ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:13:25.458749Z","caller":"traceutil/trace.go:171","msg":"trace[1818937258] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"916.593129ms","start":"2026-03-01T21:13:24.542117Z","end":"2026-03-01T21:13:25.45871Z","steps":["trace[1818937258] 'process raft request' (duration: 819.083751ms)","trace[1818937258] 'compare' (duration: 97.06283ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:13:25.458815Z","caller":"traceutil/trace.go:171","msg":"trace[551014154] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"567.298927ms","start":"2026-03-01T21:13:24.891481Z","end":"2026-03-01T21:13:25.45878Z","steps":["trace[551014154] 'process raft request' (duration: 567.091203ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:25.458852Z","caller":"traceutil/trace.go:171","msg":"trace[1391724774] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"349.083789ms","start":"2026-03-01T21:13:25.109747Z","end":"2026-03-01T21:13:25.458831Z","steps":["trace[1391724774] 'process raft request' (duration: 349.019677ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:25.458845Z","caller":"traceutil/trace.go:171","msg":"trace[494671861] linearizableReadLoop","detail":"{readStateIndex:1641; appliedIndex:1639; }","duration":"658.787872ms","start":"2026-03-01T21:13:24.800009Z","end":"2026-03-01T21:13:25.458797Z","steps":["trace[494671861] 'read index received' (duration: 125.538541ms)","trace[494671861] 'applied index is now lower than readState.Index' (duration: 533.245651ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:13:25.458936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:24.542074Z","time spent":"916.764963ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:25.459038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:25.109704Z","time spent":"349.212501ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:25.459047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:24.891445Z","time spent":"567.467689ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:13:25.459122Z","caller":"traceutil/trace.go:171","msg":"trace[1439459885] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"469.961062ms","start":"2026-03-01T21:13:24.989147Z","end":"2026-03-01T21:13:25.459108Z","steps":["trace[1439459885] 'process raft request' (duration: 469.563583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:25.459203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:24.989127Z","time spent":"470.034413ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:13:25.459274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.250801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61de0a39-01f2-4434-b59a-2ccd5e2c2374\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-03-01T21:13:25.459321Z","caller":"traceutil/trace.go:171","msg":"trace[585197382] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-61de0a39-01f2-4434-b59a-2ccd5e2c2374; range_end:; response_count:1; response_revision:1579; }","duration":"659.367373ms","start":"2026-03-01T21:13:24.799938Z","end":"2026-03-01T21:13:25.459306Z","steps":["trace[585197382] 'agreement among raft nodes before linearized reading' (duration: 659.081647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:25.459358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:24.799914Z","time spent":"659.435724ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-61de0a39-01f2-4434-b59a-2ccd5e2c2374\" "} {"level":"warn","ts":"2026-03-01T21:13:25.459378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.297659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:13:25.459447Z","caller":"traceutil/trace.go:171","msg":"trace[765845590] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1579; }","duration":"276.394382ms","start":"2026-03-01T21:13:25.18303Z","end":"2026-03-01T21:13:25.459424Z","steps":["trace[765845590] 'agreement among raft nodes before linearized reading' (duration: 276.270999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:25.459466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.63662ms","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-01T21:13:25.459469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.912222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-03-01T21:13:25.459434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.534063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-01T21:13:25.459519Z","caller":"traceutil/trace.go:171","msg":"trace[1644171014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1579; }","duration":"156.721102ms","start":"2026-03-01T21:13:25.302784Z","end":"2026-03-01T21:13:25.459505Z","steps":["trace[1644171014] 'agreement among raft nodes before linearized reading' (duration: 156.550709ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:25.459531Z","caller":"traceutil/trace.go:171","msg":"trace[640014644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1579; }","duration":"295.003604ms","start":"2026-03-01T21:13:25.164511Z","end":"2026-03-01T21:13:25.459514Z","steps":["trace[640014644] 'agreement among raft nodes before linearized reading' (duration: 294.805391ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:13:25.45957Z","caller":"traceutil/trace.go:171","msg":"trace[842611966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1579; }","duration":"305.738867ms","start":"2026-03-01T21:13:25.153801Z","end":"2026-03-01T21:13:25.45954Z","steps":["trace[842611966] 'agreement among raft nodes before linearized reading' (duration: 305.3478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:13:25.459639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:13:25.153772Z","time spent":"305.853899ms","remote":"127.0.0.1:38590","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-01T21:14:09.583482Z","caller":"traceutil/trace.go:171","msg":"trace[1441465403] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"264.060758ms","start":"2026-03-01T21:14:09.319379Z","end":"2026-03-01T21:14:09.583439Z","steps":["trace[1441465403] 'process raft request' (duration: 263.786752ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:09.902481Z","caller":"traceutil/trace.go:171","msg":"trace[1265698373] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"195.432406ms","start":"2026-03-01T21:14:09.707015Z","end":"2026-03-01T21:14:09.902447Z","steps":["trace[1265698373] 'process raft request' (duration: 194.714182ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:09.922522Z","caller":"traceutil/trace.go:171","msg":"trace[527923967] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"214.767192ms","start":"2026-03-01T21:14:09.707718Z","end":"2026-03-01T21:14:09.922486Z","steps":["trace[527923967] 'process raft request' (duration: 214.512716ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:09.922677Z","caller":"traceutil/trace.go:171","msg":"trace[832614904] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"205.025184ms","start":"2026-03-01T21:14:09.717621Z","end":"2026-03-01T21:14:09.922646Z","steps":["trace[832614904] 'process raft request' (duration: 204.79329ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:09.92268Z","caller":"traceutil/trace.go:171","msg":"trace[613267133] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1925; }","duration":"188.28896ms","start":"2026-03-01T21:14:09.734347Z","end":"2026-03-01T21:14:09.922636Z","steps":["trace[613267133] 'read index received' (duration: 167.463176ms)","trace[613267133] 'applied index is now lower than readState.Index' (duration: 20.823834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:14:09.922902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.537624ms","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-01T21:14:09.922945Z","caller":"traceutil/trace.go:171","msg":"trace[1714845652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1853; }","duration":"188.650587ms","start":"2026-03-01T21:14:09.734287Z","end":"2026-03-01T21:14:09.922938Z","steps":["trace[1714845652] 'agreement among raft nodes before linearized reading' (duration: 188.454873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:09.923116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.942265ms","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-01T21:14:09.923141Z","caller":"traceutil/trace.go:171","msg":"trace[1271170675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1853; }","duration":"168.022826ms","start":"2026-03-01T21:14:09.755114Z","end":"2026-03-01T21:14:09.923136Z","steps":["trace[1271170675] 'agreement among raft nodes before linearized reading' (duration: 167.959505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:09.923216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.789593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-01T21:14:09.923242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.409595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:14:09.923298Z","caller":"traceutil/trace.go:171","msg":"trace[358916519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1853; }","duration":"179.979377ms","start":"2026-03-01T21:14:09.743297Z","end":"2026-03-01T21:14:09.923276Z","steps":["trace[358916519] 'agreement among raft nodes before linearized reading' (duration: 179.693982ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:09.923319Z","caller":"traceutil/trace.go:171","msg":"trace[1859664747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"167.520316ms","start":"2026-03-01T21:14:09.755759Z","end":"2026-03-01T21:14:09.923279Z","steps":["trace[1859664747] 'agreement among raft nodes before linearized reading' (duration: 167.318412ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:15.865856Z","caller":"traceutil/trace.go:171","msg":"trace[1779439097] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"239.294737ms","start":"2026-03-01T21:14:15.626527Z","end":"2026-03-01T21:14:15.865822Z","steps":["trace[1779439097] 'process raft request' (duration: 239.110973ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:16.045314Z","caller":"traceutil/trace.go:171","msg":"trace[1894475410] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"103.293352ms","start":"2026-03-01T21:14:15.941971Z","end":"2026-03-01T21:14:16.045264Z","steps":["trace[1894475410] 'process raft request' (duration: 102.945825ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:16.045337Z","caller":"traceutil/trace.go:171","msg":"trace[1890614934] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"101.274492ms","start":"2026-03-01T21:14:15.944021Z","end":"2026-03-01T21:14:16.045296Z","steps":["trace[1890614934] 'process raft request' (duration: 101.070428ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:14:17.643571Z","caller":"traceutil/trace.go:171","msg":"trace[1034141132] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"357.705112ms","start":"2026-03-01T21:14:17.285837Z","end":"2026-03-01T21:14:17.643542Z","steps":["trace[1034141132] 'process raft request' (duration: 357.417026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:14:17.643777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:14:17.285822Z","time spent":"357.851105ms","remote":"127.0.0.1:41048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:15:10.571467Z","caller":"traceutil/trace.go:171","msg":"trace[360055724] linearizableReadLoop","detail":"{readStateIndex:2411; appliedIndex:2410; }","duration":"333.579681ms","start":"2026-03-01T21:15:10.237852Z","end":"2026-03-01T21:15:10.571431Z","steps":["trace[360055724] 'read index received' (duration: 333.491459ms)","trace[360055724] 'applied index is now lower than readState.Index' (duration: 86.492µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:15:10.571596Z","caller":"traceutil/trace.go:171","msg":"trace[867517775] transaction","detail":"{read_only:false; response_revision:2315; number_of_response:1; }","duration":"425.177985ms","start":"2026-03-01T21:15:10.146387Z","end":"2026-03-01T21:15:10.571565Z","steps":["trace[867517775] 'process raft request' (duration: 424.819459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:10.571758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.873876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:15:10.571828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:10.14637Z","time spent":"425.295377ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:15:10.571874Z","caller":"traceutil/trace.go:171","msg":"trace[556834113] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2315; }","duration":"334.048531ms","start":"2026-03-01T21:15:10.2378Z","end":"2026-03-01T21:15:10.571848Z","steps":["trace[556834113] 'agreement among raft nodes before linearized reading' (duration: 333.819395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:10.571935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:10.237776Z","time spent":"334.144203ms","remote":"127.0.0.1:40944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"info","ts":"2026-03-01T21:15:10.574885Z","caller":"traceutil/trace.go:171","msg":"trace[684551097] transaction","detail":"{read_only:false; response_revision:2316; number_of_response:1; }","duration":"250.061119ms","start":"2026-03-01T21:15:10.324791Z","end":"2026-03-01T21:15:10.574852Z","steps":["trace[684551097] 'process raft request' (duration: 249.796424ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:10.574878Z","caller":"traceutil/trace.go:171","msg":"trace[1685215213] transaction","detail":"{read_only:false; response_revision:2317; number_of_response:1; }","duration":"249.953337ms","start":"2026-03-01T21:15:10.324888Z","end":"2026-03-01T21:15:10.574841Z","steps":["trace[1685215213] 'process raft request' (duration: 249.864705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:10.575089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.277758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:15:10.575156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.809053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-01T21:15:10.575172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.367471ms","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-01T21:15:10.575183Z","caller":"traceutil/trace.go:171","msg":"trace[1727069652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2318; }","duration":"276.363819ms","start":"2026-03-01T21:15:10.298767Z","end":"2026-03-01T21:15:10.575131Z","steps":["trace[1727069652] 'agreement among raft nodes before linearized reading' (duration: 276.244697ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:10.575205Z","caller":"traceutil/trace.go:171","msg":"trace[1171632417] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2318; }","duration":"182.903775ms","start":"2026-03-01T21:15:10.392288Z","end":"2026-03-01T21:15:10.575192Z","steps":["trace[1171632417] 'agreement among raft nodes before linearized reading' (duration: 182.740781ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:10.575221Z","caller":"traceutil/trace.go:171","msg":"trace[864521828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2318; }","duration":"185.477573ms","start":"2026-03-01T21:15:10.389729Z","end":"2026-03-01T21:15:10.575207Z","steps":["trace[864521828] 'agreement among raft nodes before linearized reading' (duration: 185.34311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:10.57521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.257927ms","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-01T21:15:10.575266Z","caller":"traceutil/trace.go:171","msg":"trace[563090059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2318; }","duration":"190.374049ms","start":"2026-03-01T21:15:10.384876Z","end":"2026-03-01T21:15:10.57525Z","steps":["trace[563090059] 'agreement among raft nodes before linearized reading' (duration: 190.219216ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:10.575218Z","caller":"traceutil/trace.go:171","msg":"trace[296729318] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"238.445523ms","start":"2026-03-01T21:15:10.3367Z","end":"2026-03-01T21:15:10.575146Z","steps":["trace[296729318] 'process raft request' (duration: 238.098006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:10.575297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.243792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:15:10.57535Z","caller":"traceutil/trace.go:171","msg":"trace[349718712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2318; }","duration":"179.319083ms","start":"2026-03-01T21:15:10.396019Z","end":"2026-03-01T21:15:10.575338Z","steps":["trace[349718712] 'agreement among raft nodes before linearized reading' (duration: 179.13302ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:45.262827Z","caller":"traceutil/trace.go:171","msg":"trace[1942022605] transaction","detail":"{read_only:false; response_revision:2505; number_of_response:1; }","duration":"511.383035ms","start":"2026-03-01T21:15:44.751398Z","end":"2026-03-01T21:15:45.262781Z","steps":["trace[1942022605] 'process raft request' (duration: 509.750774ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:45.262833Z","caller":"traceutil/trace.go:171","msg":"trace[391059253] transaction","detail":"{read_only:false; response_revision:2506; number_of_response:1; }","duration":"506.768016ms","start":"2026-03-01T21:15:44.756019Z","end":"2026-03-01T21:15:45.262787Z","steps":["trace[391059253] 'process raft request' (duration: 506.559052ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:45.262673Z","caller":"traceutil/trace.go:171","msg":"trace[1516303301] linearizableReadLoop","detail":"{readStateIndex:2609; appliedIndex:2606; }","duration":"483.223558ms","start":"2026-03-01T21:15:44.77942Z","end":"2026-03-01T21:15:45.262643Z","steps":["trace[1516303301] 'read index received' (duration: 46.286479ms)","trace[1516303301] 'applied index is now lower than readState.Index' (duration: 436.935809ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:15:45.263045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:44.755994Z","time spent":"506.93951ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:15:45.263052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.625386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T21:15:45.263121Z","caller":"traceutil/trace.go:171","msg":"trace[661599818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2506; }","duration":"483.720568ms","start":"2026-03-01T21:15:44.77938Z","end":"2026-03-01T21:15:45.263101Z","steps":["trace[661599818] 'agreement among raft nodes before linearized reading' (duration: 483.477083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:45.263155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.567038ms","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-01T21:15:45.263174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:44.751367Z","time spent":"511.542978ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:15:45.263266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.003529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-01T21:15:45.263306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.334715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-01T21:15:45.263311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.431204ms","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-01T21:15:45.263326Z","caller":"traceutil/trace.go:171","msg":"trace[1706819626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2506; }","duration":"422.137031ms","start":"2026-03-01T21:15:44.841173Z","end":"2026-03-01T21:15:45.26331Z","steps":["trace[1706819626] 'agreement among raft nodes before linearized reading' (duration: 421.990158ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:45.263358Z","caller":"traceutil/trace.go:171","msg":"trace[625239254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2506; }","duration":"428.502116ms","start":"2026-03-01T21:15:44.834847Z","end":"2026-03-01T21:15:45.263349Z","steps":["trace[625239254] 'agreement among raft nodes before linearized reading' (duration: 428.405154ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:15:45.263357Z","caller":"traceutil/trace.go:171","msg":"trace[585532335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2506; }","duration":"422.404907ms","start":"2026-03-01T21:15:44.840939Z","end":"2026-03-01T21:15:45.263344Z","steps":["trace[585532335] 'agreement among raft nodes before linearized reading' (duration: 422.283464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:45.263377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:44.841147Z","time spent":"422.218093ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T21:15:45.2634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:44.834833Z","time spent":"428.558386ms","remote":"127.0.0.1:38590","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-01T21:15:45.263183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:44.779368Z","time spent":"483.803079ms","remote":"127.0.0.1:38590","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-01T21:15:45.263403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:44.840928Z","time spent":"422.464858ms","remote":"127.0.0.1:38590","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-01T21:15:45.263225Z","caller":"traceutil/trace.go:171","msg":"trace[1748012516] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2506; }","duration":"458.692801ms","start":"2026-03-01T21:15:44.804517Z","end":"2026-03-01T21:15:45.26321Z","steps":["trace[1748012516] 'agreement among raft nodes before linearized reading' (duration: 458.476197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:15:45.263563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:15:44.804502Z","time spent":"459.051589ms","remote":"127.0.0.1:38590","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-01T21:15:45.263689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.934634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.1898d428cf47bc81\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-03-01T21:15:45.263739Z","caller":"traceutil/trace.go:171","msg":"trace[5920553] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.1898d428cf47bc81; range_end:; response_count:1; response_revision:2506; }","duration":"148.033206ms","start":"2026-03-01T21:15:45.115694Z","end":"2026-03-01T21:15:45.263727Z","steps":["trace[5920553] 'agreement among raft nodes before linearized reading' (duration: 147.840072ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:18:21.618019Z","caller":"traceutil/trace.go:171","msg":"trace[1637479107] transaction","detail":"{read_only:false; response_revision:3454; number_of_response:1; }","duration":"148.526845ms","start":"2026-03-01T21:18:21.469461Z","end":"2026-03-01T21:18:21.617988Z","steps":["trace[1637479107] 'process raft request' (duration: 148.348301ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:32.734126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.976727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:19:32.734298Z","caller":"traceutil/trace.go:171","msg":"trace[603973014] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:3903; }","duration":"116.189241ms","start":"2026-03-01T21:19:32.618084Z","end":"2026-03-01T21:19:32.734274Z","steps":["trace[603973014] 'count revisions from in-memory index tree' (duration: 115.821924ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:37.844789Z","caller":"traceutil/trace.go:171","msg":"trace[70736328] linearizableReadLoop","detail":"{readStateIndex:4419; appliedIndex:4418; }","duration":"173.298283ms","start":"2026-03-01T21:19:37.671461Z","end":"2026-03-01T21:19:37.844759Z","steps":["trace[70736328] 'read index received' (duration: 172.526847ms)","trace[70736328] 'applied index is now lower than readState.Index' (duration: 770.105µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:37.844888Z","caller":"traceutil/trace.go:171","msg":"trace[354662772] transaction","detail":"{read_only:false; response_revision:4242; number_of_response:1; }","duration":"177.309643ms","start":"2026-03-01T21:19:37.667531Z","end":"2026-03-01T21:19:37.844841Z","steps":["trace[354662772] 'process raft request' (duration: 176.69624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:37.845008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.527037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:19:37.845054Z","caller":"traceutil/trace.go:171","msg":"trace[681726424] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:0; response_revision:4242; }","duration":"173.659349ms","start":"2026-03-01T21:19:37.671382Z","end":"2026-03-01T21:19:37.845041Z","steps":["trace[681726424] 'agreement among raft nodes before linearized reading' (duration: 173.557947ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:37.84674Z","caller":"traceutil/trace.go:171","msg":"trace[1526104278] transaction","detail":"{read_only:false; response_revision:4243; number_of_response:1; }","duration":"113.572989ms","start":"2026-03-01T21:19:37.733118Z","end":"2026-03-01T21:19:37.846691Z","steps":["trace[1526104278] 'process raft request' (duration: 113.281323ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:39.123987Z","caller":"traceutil/trace.go:171","msg":"trace[1057651280] linearizableReadLoop","detail":"{readStateIndex:4467; appliedIndex:4466; }","duration":"223.385623ms","start":"2026-03-01T21:19:38.900574Z","end":"2026-03-01T21:19:39.123959Z","steps":["trace[1057651280] 'read index received' (duration: 223.274581ms)","trace[1057651280] 'applied index is now lower than readState.Index' (duration: 107.902µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:39.124135Z","caller":"traceutil/trace.go:171","msg":"trace[1557183358] transaction","detail":"{read_only:false; response_revision:4290; number_of_response:1; }","duration":"240.629065ms","start":"2026-03-01T21:19:38.883496Z","end":"2026-03-01T21:19:39.124126Z","steps":["trace[1557183358] 'process raft request' (duration: 240.251738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:39.124474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.886123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-crw9b\" ","response":"range_response_count:1 size:5042"} {"level":"info","ts":"2026-03-01T21:19:39.12451Z","caller":"traceutil/trace.go:171","msg":"trace[1095601590] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-crw9b; range_end:; response_count:1; response_revision:4290; }","duration":"223.974645ms","start":"2026-03-01T21:19:38.900523Z","end":"2026-03-01T21:19:39.124498Z","steps":["trace[1095601590] 'agreement among raft nodes before linearized reading' (duration: 223.835182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:39.129861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.828621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:16305"} {"level":"warn","ts":"2026-03-01T21:19:39.129905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.068589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:19:39.129955Z","caller":"traceutil/trace.go:171","msg":"trace[893081444] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:4290; }","duration":"111.175991ms","start":"2026-03-01T21:19:39.018766Z","end":"2026-03-01T21:19:39.129942Z","steps":["trace[893081444] 'agreement among raft nodes before linearized reading' (duration: 111.08465ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:39.12996Z","caller":"traceutil/trace.go:171","msg":"trace[341221503] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:4290; }","duration":"124.991214ms","start":"2026-03-01T21:19:39.004942Z","end":"2026-03-01T21:19:39.129933Z","steps":["trace[341221503] 'agreement among raft nodes before linearized reading' (duration: 124.694889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:39.130235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.344274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:19:39.130257Z","caller":"traceutil/trace.go:171","msg":"trace[1509293871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4290; }","duration":"123.417465ms","start":"2026-03-01T21:19:39.006833Z","end":"2026-03-01T21:19:39.130251Z","steps":["trace[1509293871] 'agreement among raft nodes before linearized reading' (duration: 123.368374ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:39.130387Z","caller":"traceutil/trace.go:171","msg":"trace[1187894546] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4290; }","duration":"190.552914ms","start":"2026-03-01T21:19:38.939809Z","end":"2026-03-01T21:19:39.130362Z","steps":["trace[1187894546] 'process raft request' (duration: 189.691486ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:40.370525Z","caller":"traceutil/trace.go:171","msg":"trace[880819974] transaction","detail":"{read_only:false; response_revision:4365; number_of_response:1; }","duration":"142.544353ms","start":"2026-03-01T21:19:40.227952Z","end":"2026-03-01T21:19:40.370496Z","steps":["trace[880819974] 'process raft request' (duration: 142.38305ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:40.383168Z","caller":"traceutil/trace.go:171","msg":"trace[703709114] transaction","detail":"{read_only:false; response_revision:4366; number_of_response:1; }","duration":"138.623786ms","start":"2026-03-01T21:19:40.24452Z","end":"2026-03-01T21:19:40.383144Z","steps":["trace[703709114] 'process raft request' (duration: 138.384071ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:40.383128Z","caller":"traceutil/trace.go:171","msg":"trace[1705463225] linearizableReadLoop","detail":"{readStateIndex:4545; appliedIndex:4543; }","duration":"124.456955ms","start":"2026-03-01T21:19:40.258625Z","end":"2026-03-01T21:19:40.383082Z","steps":["trace[1705463225] 'read index received' (duration: 111.819175ms)","trace[1705463225] 'applied index is now lower than readState.Index' (duration: 12.63642ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:19:40.384285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.630528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2194600"} {"level":"info","ts":"2026-03-01T21:19:40.384362Z","caller":"traceutil/trace.go:171","msg":"trace[609126005] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4366; }","duration":"125.780771ms","start":"2026-03-01T21:19:40.258565Z","end":"2026-03-01T21:19:40.384346Z","steps":["trace[609126005] 'agreement among raft nodes before linearized reading' (duration: 124.654179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:43.41062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.12768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:19:43.410721Z","caller":"traceutil/trace.go:171","msg":"trace[1306283959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4434; }","duration":"111.249183ms","start":"2026-03-01T21:19:43.29945Z","end":"2026-03-01T21:19:43.410699Z","steps":["trace[1306283959] 'range keys from in-memory index tree' (duration: 111.043139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:45.35019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.478338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:19:45.350386Z","caller":"traceutil/trace.go:171","msg":"trace[534063626] linearizableReadLoop","detail":"{readStateIndex:4652; appliedIndex:4651; }","duration":"229.078897ms","start":"2026-03-01T21:19:45.121277Z","end":"2026-03-01T21:19:45.350356Z","steps":["trace[534063626] 'read index received' (duration: 71.111819ms)","trace[534063626] 'applied index is now lower than readState.Index' (duration: 157.964877ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:45.350405Z","caller":"traceutil/trace.go:171","msg":"trace[2125156610] transaction","detail":"{read_only:false; response_revision:4470; number_of_response:1; }","duration":"229.151499ms","start":"2026-03-01T21:19:45.121212Z","end":"2026-03-01T21:19:45.350363Z","steps":["trace[2125156610] 'process raft request' (duration: 71.14787ms)","trace[2125156610] 'compare' (duration: 157.190113ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:19:45.350635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.328722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:19:45.350724Z","caller":"traceutil/trace.go:171","msg":"trace[1872217268] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4470; }","duration":"229.514495ms","start":"2026-03-01T21:19:45.121193Z","end":"2026-03-01T21:19:45.350708Z","steps":["trace[1872217268] 'agreement among raft nodes before linearized reading' (duration: 229.299511ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.390417Z","caller":"traceutil/trace.go:171","msg":"trace[889530861] transaction","detail":"{read_only:false; response_revision:4472; number_of_response:1; }","duration":"262.169772ms","start":"2026-03-01T21:19:45.128221Z","end":"2026-03-01T21:19:45.390391Z","steps":["trace[889530861] 'process raft request' (duration: 262.076101ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.390449Z","caller":"traceutil/trace.go:171","msg":"trace[157657449] transaction","detail":"{read_only:false; response_revision:4471; number_of_response:1; }","duration":"262.568191ms","start":"2026-03-01T21:19:45.127851Z","end":"2026-03-01T21:19:45.390419Z","steps":["trace[157657449] 'process raft request' (duration: 262.359647ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.390521Z","caller":"traceutil/trace.go:171","msg":"trace[359549059] transaction","detail":"{read_only:false; response_revision:4475; number_of_response:1; }","duration":"259.266474ms","start":"2026-03-01T21:19:45.131216Z","end":"2026-03-01T21:19:45.390483Z","steps":["trace[359549059] 'process raft request' (duration: 259.155232ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.390462Z","caller":"traceutil/trace.go:171","msg":"trace[1532989850] transaction","detail":"{read_only:false; response_revision:4473; number_of_response:1; }","duration":"261.492958ms","start":"2026-03-01T21:19:45.128946Z","end":"2026-03-01T21:19:45.390439Z","steps":["trace[1532989850] 'process raft request' (duration: 261.378556ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.390612Z","caller":"traceutil/trace.go:171","msg":"trace[1005880109] transaction","detail":"{read_only:false; response_revision:4474; number_of_response:1; }","duration":"259.414498ms","start":"2026-03-01T21:19:45.131139Z","end":"2026-03-01T21:19:45.390554Z","steps":["trace[1005880109] 'process raft request' (duration: 259.208954ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.39065Z","caller":"traceutil/trace.go:171","msg":"trace[533582835] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"251.166153ms","start":"2026-03-01T21:19:45.139473Z","end":"2026-03-01T21:19:45.390639Z","steps":["trace[533582835] 'process raft request' (duration: 250.923679ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.514834Z","caller":"traceutil/trace.go:171","msg":"trace[1264417479] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"108.628682ms","start":"2026-03-01T21:19:45.40618Z","end":"2026-03-01T21:19:45.514808Z","steps":["trace[1264417479] 'process raft request' (duration: 108.486669ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.514837Z","caller":"traceutil/trace.go:171","msg":"trace[199494893] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"116.293424ms","start":"2026-03-01T21:19:45.398506Z","end":"2026-03-01T21:19:45.514799Z","steps":["trace[199494893] 'process raft request' (duration: 115.964796ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:45.895099Z","caller":"traceutil/trace.go:171","msg":"trace[1677480027] linearizableReadLoop","detail":"{readStateIndex:4669; appliedIndex:4668; }","duration":"187.742568ms","start":"2026-03-01T21:19:45.707328Z","end":"2026-03-01T21:19:45.895071Z","steps":["trace[1677480027] 'read index received' (duration: 187.547834ms)","trace[1677480027] 'applied index is now lower than readState.Index' (duration: 193.304µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:45.895231Z","caller":"traceutil/trace.go:171","msg":"trace[1194232615] transaction","detail":"{read_only:false; response_revision:4486; number_of_response:1; }","duration":"189.449492ms","start":"2026-03-01T21:19:45.705745Z","end":"2026-03-01T21:19:45.895194Z","steps":["trace[1194232615] 'process raft request' (duration: 189.166526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:45.895357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.004793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-03-01T21:19:45.895432Z","caller":"traceutil/trace.go:171","msg":"trace[1513351826] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4486; }","duration":"188.103995ms","start":"2026-03-01T21:19:45.707309Z","end":"2026-03-01T21:19:45.895413Z","steps":["trace[1513351826] 'agreement among raft nodes before linearized reading' (duration: 187.8628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:45.895524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.79557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75474"} {"level":"info","ts":"2026-03-01T21:19:45.895568Z","caller":"traceutil/trace.go:171","msg":"trace[1036143550] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4486; }","duration":"185.893593ms","start":"2026-03-01T21:19:45.709665Z","end":"2026-03-01T21:19:45.895559Z","steps":["trace[1036143550] 'agreement among raft nodes before linearized reading' (duration: 185.572876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:45.895569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.818611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-01T21:19:45.895667Z","caller":"traceutil/trace.go:171","msg":"trace[1987957739] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4486; }","duration":"185.947753ms","start":"2026-03-01T21:19:45.709699Z","end":"2026-03-01T21:19:45.895647Z","steps":["trace[1987957739] 'agreement among raft nodes before linearized reading' (duration: 185.618896ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:47.00901Z","caller":"traceutil/trace.go:171","msg":"trace[499450628] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"158.913278ms","start":"2026-03-01T21:19:46.850078Z","end":"2026-03-01T21:19:47.008992Z","steps":["trace[499450628] 'process raft request' (duration: 158.797095ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:47.145813Z","caller":"traceutil/trace.go:171","msg":"trace[1851214263] linearizableReadLoop","detail":"{readStateIndex:4682; appliedIndex:4678; }","duration":"138.863271ms","start":"2026-03-01T21:19:47.006925Z","end":"2026-03-01T21:19:47.145788Z","steps":["trace[1851214263] 'read index received' (duration: 2.159543ms)","trace[1851214263] 'applied index is now lower than readState.Index' (duration: 136.702148ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:47.145844Z","caller":"traceutil/trace.go:171","msg":"trace[477234631] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"294.592894ms","start":"2026-03-01T21:19:46.851203Z","end":"2026-03-01T21:19:47.145796Z","steps":["trace[477234631] 'process raft request' (duration: 294.462332ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:47.145826Z","caller":"traceutil/trace.go:171","msg":"trace[125814674] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"295.648136ms","start":"2026-03-01T21:19:46.850112Z","end":"2026-03-01T21:19:47.14576Z","steps":["trace[125814674] 'process raft request' (duration: 295.38297ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:47.145986Z","caller":"traceutil/trace.go:171","msg":"trace[55435621] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"294.629865ms","start":"2026-03-01T21:19:46.851299Z","end":"2026-03-01T21:19:47.145929Z","steps":["trace[55435621] 'process raft request' (duration: 294.410801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:47.146037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.177407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-7swvh.1898d4658d49330b\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-03-01T21:19:47.146087Z","caller":"traceutil/trace.go:171","msg":"trace[814539598] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-7swvh.1898d4658d49330b; range_end:; response_count:1; response_revision:4498; }","duration":"137.259749ms","start":"2026-03-01T21:19:47.008813Z","end":"2026-03-01T21:19:47.146072Z","steps":["trace[814539598] 'agreement among raft nodes before linearized reading' (duration: 137.127296ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:47.14609Z","caller":"traceutil/trace.go:171","msg":"trace[720704849] transaction","detail":"{read_only:false; response_revision:4498; number_of_response:1; }","duration":"292.488152ms","start":"2026-03-01T21:19:46.853593Z","end":"2026-03-01T21:19:47.146081Z","steps":["trace[720704849] 'process raft request' (duration: 292.155105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:47.146039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.098036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7swvh\" ","response":"range_response_count:1 size:9830"} {"level":"info","ts":"2026-03-01T21:19:47.146173Z","caller":"traceutil/trace.go:171","msg":"trace[889410406] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-7swvh; range_end:; response_count:1; response_revision:4498; }","duration":"139.291319ms","start":"2026-03-01T21:19:47.006873Z","end":"2026-03-01T21:19:47.146165Z","steps":["trace[889410406] 'agreement among raft nodes before linearized reading' (duration: 139.039155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:50.743576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.09621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c1d38d3-4291-494a-987a-5c3d712cba68\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-01T21:19:50.743691Z","caller":"traceutil/trace.go:171","msg":"trace[1814234168] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c1d38d3-4291-494a-987a-5c3d712cba68; range_end:; response_count:1; response_revision:4630; }","duration":"117.273063ms","start":"2026-03-01T21:19:50.626394Z","end":"2026-03-01T21:19:50.743667Z","steps":["trace[1814234168] 'range keys from in-memory index tree' (duration: 116.891706ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:50.81575Z","caller":"traceutil/trace.go:171","msg":"trace[1671587652] linearizableReadLoop","detail":"{readStateIndex:4818; appliedIndex:4817; }","duration":"122.624698ms","start":"2026-03-01T21:19:50.693091Z","end":"2026-03-01T21:19:50.815716Z","steps":["trace[1671587652] 'read index received' (duration: 121.939135ms)","trace[1671587652] 'applied index is now lower than readState.Index' (duration: 684.643µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:19:50.815822Z","caller":"traceutil/trace.go:171","msg":"trace[130891170] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"188.102335ms","start":"2026-03-01T21:19:50.627672Z","end":"2026-03-01T21:19:50.815775Z","steps":["trace[130891170] 'process raft request' (duration: 187.611885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:50.81592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.785751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:19:50.815964Z","caller":"traceutil/trace.go:171","msg":"trace[287009114] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:4631; }","duration":"122.890524ms","start":"2026-03-01T21:19:50.693062Z","end":"2026-03-01T21:19:50.815952Z","steps":["trace[287009114] 'agreement among raft nodes before linearized reading' (duration: 122.772822ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:19:51.244567Z","caller":"traceutil/trace.go:171","msg":"trace[1242266501] linearizableReadLoop","detail":"{readStateIndex:4819; appliedIndex:4818; }","duration":"340.3012ms","start":"2026-03-01T21:19:50.904223Z","end":"2026-03-01T21:19:51.244524Z","steps":["trace[1242266501] 'read index received' (duration: 298.184336ms)","trace[1242266501] 'applied index is now lower than readState.Index' (duration: 42.115114ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:19:51.244593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:19:50.853492Z","time spent":"391.091926ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-01T21:19:51.244863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.627516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-01T21:19:51.244907Z","caller":"traceutil/trace.go:171","msg":"trace[1350281353] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4631; }","duration":"340.714668ms","start":"2026-03-01T21:19:50.904176Z","end":"2026-03-01T21:19:51.24489Z","steps":["trace[1350281353] 'agreement among raft nodes before linearized reading' (duration: 340.518234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:51.244941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:19:50.904157Z","time spent":"340.777099ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-01T21:19:51.244978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.335783ms","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-01T21:19:51.24506Z","caller":"traceutil/trace.go:171","msg":"trace[1166933157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4631; }","duration":"222.482066ms","start":"2026-03-01T21:19:51.022558Z","end":"2026-03-01T21:19:51.24504Z","steps":["trace[1166933157] 'agreement among raft nodes before linearized reading' (duration: 222.256812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:51.245158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.706323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16305"} {"level":"info","ts":"2026-03-01T21:19:51.245243Z","caller":"traceutil/trace.go:171","msg":"trace[1858777993] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4631; }","duration":"111.821794ms","start":"2026-03-01T21:19:51.133378Z","end":"2026-03-01T21:19:51.245199Z","steps":["trace[1858777993] 'agreement among raft nodes before linearized reading' (duration: 111.59669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:19:51.245283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.162334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4c1d38d3-4291-494a-987a-5c3d712cba68\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-01T21:19:51.245364Z","caller":"traceutil/trace.go:171","msg":"trace[1480129098] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4c1d38d3-4291-494a-987a-5c3d712cba68; range_end:; response_count:1; response_revision:4631; }","duration":"205.240974ms","start":"2026-03-01T21:19:51.040072Z","end":"2026-03-01T21:19:51.245313Z","steps":["trace[1480129098] 'agreement among raft nodes before linearized reading' (duration: 205.098842ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:00.85588Z","caller":"traceutil/trace.go:171","msg":"trace[896050823] transaction","detail":"{read_only:false; response_revision:4834; number_of_response:1; }","duration":"120.515807ms","start":"2026-03-01T21:20:00.735319Z","end":"2026-03-01T21:20:00.855835Z","steps":["trace[896050823] 'process raft request' (duration: 120.263392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:00.990315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.858297ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-01T21:20:00.990701Z","caller":"traceutil/trace.go:171","msg":"trace[284610769] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"132.82535ms","start":"2026-03-01T21:20:00.857854Z","end":"2026-03-01T21:20:00.99068Z","steps":["trace[284610769] 'process raft request' (duration: 132.583246ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:01.157299Z","caller":"traceutil/trace.go:171","msg":"trace[404839935] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"161.536268ms","start":"2026-03-01T21:20:00.995731Z","end":"2026-03-01T21:20:01.157267Z","steps":["trace[404839935] 'process raft request' (duration: 101.356457ms)","trace[404839935] 'compare' (duration: 59.909656ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:20:01.157467Z","caller":"traceutil/trace.go:171","msg":"trace[1468393123] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"161.614372ms","start":"2026-03-01T21:20:00.995822Z","end":"2026-03-01T21:20:01.157436Z","steps":["trace[1468393123] 'process raft request' (duration: 161.344025ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:01.157834Z","caller":"traceutil/trace.go:171","msg":"trace[1443989940] linearizableReadLoop","detail":"{readStateIndex:5032; appliedIndex:5030; }","duration":"126.519156ms","start":"2026-03-01T21:20:01.031288Z","end":"2026-03-01T21:20:01.157807Z","steps":["trace[1443989940] 'read index received' (duration: 65.689631ms)","trace[1443989940] 'applied index is now lower than readState.Index' (duration: 60.827605ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:20:01.158104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.796451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-01T21:20:01.158168Z","caller":"traceutil/trace.go:171","msg":"trace[1251554576] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:4838; }","duration":"126.910492ms","start":"2026-03-01T21:20:01.031242Z","end":"2026-03-01T21:20:01.158152Z","steps":["trace[1251554576] 'agreement among raft nodes before linearized reading' (duration: 126.733939ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:03.811168Z","caller":"traceutil/trace.go:171","msg":"trace[877398050] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"119.956496ms","start":"2026-03-01T21:20:03.69119Z","end":"2026-03-01T21:20:03.811146Z","steps":["trace[877398050] 'process raft request' (duration: 119.467076ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:03.811442Z","caller":"traceutil/trace.go:171","msg":"trace[602351363] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"120.041537ms","start":"2026-03-01T21:20:03.691384Z","end":"2026-03-01T21:20:03.811425Z","steps":["trace[602351363] 'process raft request' (duration: 119.356734ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:04.370903Z","caller":"traceutil/trace.go:171","msg":"trace[869854418] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"109.297685ms","start":"2026-03-01T21:20:04.261554Z","end":"2026-03-01T21:20:04.370852Z","steps":["trace[869854418] 'process raft request' (duration: 109.135912ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:04.370942Z","caller":"traceutil/trace.go:171","msg":"trace[2124711898] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"110.698863ms","start":"2026-03-01T21:20:04.260202Z","end":"2026-03-01T21:20:04.370901Z","steps":["trace[2124711898] 'process raft request' (duration: 110.302815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:06.715323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.041814ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-01T21:20:06.715644Z","caller":"traceutil/trace.go:171","msg":"trace[1006724655] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"206.593212ms","start":"2026-03-01T21:20:06.50903Z","end":"2026-03-01T21:20:06.715623Z","steps":["trace[1006724655] 'process raft request' (duration: 206.537811ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:06.715736Z","caller":"traceutil/trace.go:171","msg":"trace[982017728] linearizableReadLoop","detail":"{readStateIndex:5087; appliedIndex:5086; }","duration":"210.172203ms","start":"2026-03-01T21:20:06.505479Z","end":"2026-03-01T21:20:06.715652Z","steps":["trace[982017728] 'read index received' (duration: 63.803883ms)","trace[982017728] 'applied index is now lower than readState.Index' (duration: 146.36288ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:20:06.716002Z","caller":"traceutil/trace.go:171","msg":"trace[280700935] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"208.409208ms","start":"2026-03-01T21:20:06.507555Z","end":"2026-03-01T21:20:06.715964Z","steps":["trace[280700935] 'process raft request' (duration: 207.871987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:06.71936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.840675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-5c5f7c5675-9dxvt\" ","response":"range_response_count:1 size:11848"} {"level":"info","ts":"2026-03-01T21:20:06.719477Z","caller":"traceutil/trace.go:171","msg":"trace[1929487726] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-5c5f7c5675-9dxvt; range_end:; response_count:1; response_revision:4894; }","duration":"214.032049ms","start":"2026-03-01T21:20:06.505418Z","end":"2026-03-01T21:20:06.71945Z","steps":["trace[1929487726] 'agreement among raft nodes before linearized reading' (duration: 210.420667ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:09.414287Z","caller":"traceutil/trace.go:171","msg":"trace[1001994494] linearizableReadLoop","detail":"{readStateIndex:5170; appliedIndex:5169; }","duration":"117.793543ms","start":"2026-03-01T21:20:09.296458Z","end":"2026-03-01T21:20:09.414252Z","steps":["trace[1001994494] 'read index received' (duration: 92.179036ms)","trace[1001994494] 'applied index is now lower than readState.Index' (duration: 25.612647ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:20:09.414777Z","caller":"traceutil/trace.go:171","msg":"trace[213444236] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"122.831443ms","start":"2026-03-01T21:20:09.291922Z","end":"2026-03-01T21:20:09.414754Z","steps":["trace[213444236] 'process raft request' (duration: 96.731596ms)","trace[213444236] 'compare' (duration: 25.448154ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:20:09.415167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.68902ms","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-01T21:20:09.415228Z","caller":"traceutil/trace.go:171","msg":"trace[1708686245] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4973; }","duration":"118.800203ms","start":"2026-03-01T21:20:09.296414Z","end":"2026-03-01T21:20:09.415214Z","steps":["trace[1708686245] 'agreement among raft nodes before linearized reading' (duration: 118.602719ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:10.480985Z","caller":"traceutil/trace.go:171","msg":"trace[706423093] linearizableReadLoop","detail":"{readStateIndex:5207; appliedIndex:5206; }","duration":"360.024551ms","start":"2026-03-01T21:20:10.120929Z","end":"2026-03-01T21:20:10.480954Z","steps":["trace[706423093] 'read index received' (duration: 359.834077ms)","trace[706423093] 'applied index is now lower than readState.Index' (duration: 188.774µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:20:10.481101Z","caller":"traceutil/trace.go:171","msg":"trace[146704465] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"390.81133ms","start":"2026-03-01T21:20:10.090251Z","end":"2026-03-01T21:20:10.481063Z","steps":["trace[146704465] 'process raft request' (duration: 390.512304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:10.481321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.511159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e019ad0-3d4a-4730-9159-5c06d46e08c8\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-03-01T21:20:10.481318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.090216Z","time spent":"390.973493ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:20:10.481364Z","caller":"traceutil/trace.go:171","msg":"trace[1830889965] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e019ad0-3d4a-4730-9159-5c06d46e08c8; range_end:; response_count:1; response_revision:5008; }","duration":"311.584451ms","start":"2026-03-01T21:20:10.169767Z","end":"2026-03-01T21:20:10.481352Z","steps":["trace[1830889965] 'agreement among raft nodes before linearized reading' (duration: 311.375457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:10.481399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.169676Z","time spent":"311.715713ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e019ad0-3d4a-4730-9159-5c06d46e08c8\" "} {"level":"warn","ts":"2026-03-01T21:20:10.481388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.461124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:20:10.481482Z","caller":"traceutil/trace.go:171","msg":"trace[1387132888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5008; }","duration":"182.625797ms","start":"2026-03-01T21:20:10.298833Z","end":"2026-03-01T21:20:10.481459Z","steps":["trace[1387132888] 'agreement among raft nodes before linearized reading' (duration: 182.399452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:10.481698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.336392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5e019ad0-3d4a-4730-9159-5c06d46e08c8\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-01T21:20:10.481816Z","caller":"traceutil/trace.go:171","msg":"trace[635263382] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5e019ad0-3d4a-4730-9159-5c06d46e08c8; range_end:; response_count:1; response_revision:5008; }","duration":"229.534246ms","start":"2026-03-01T21:20:10.252259Z","end":"2026-03-01T21:20:10.481793Z","steps":["trace[635263382] 'agreement among raft nodes before linearized reading' (duration: 229.074047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:10.481988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.058381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2209360"} {"level":"info","ts":"2026-03-01T21:20:10.482054Z","caller":"traceutil/trace.go:171","msg":"trace[1687679028] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5008; }","duration":"361.199674ms","start":"2026-03-01T21:20:10.12084Z","end":"2026-03-01T21:20:10.482039Z","steps":["trace[1687679028] 'agreement among raft nodes before linearized reading' (duration: 360.238325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:10.482091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.12079Z","time spent":"361.290606ms","remote":"127.0.0.1:41076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2209383,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-03-01T21:20:10.645339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.517469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:20:10.645455Z","caller":"traceutil/trace.go:171","msg":"trace[1910563731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5008; }","duration":"162.680382ms","start":"2026-03-01T21:20:10.482748Z","end":"2026-03-01T21:20:10.645429Z","steps":["trace[1910563731] 'range keys from in-memory index tree' (duration: 162.219023ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:10.663669Z","caller":"traceutil/trace.go:171","msg":"trace[1875839389] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"174.495996ms","start":"2026-03-01T21:20:10.489142Z","end":"2026-03-01T21:20:10.663638Z","steps":["trace[1875839389] 'process raft request' (duration: 174.238841ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:10.663765Z","caller":"traceutil/trace.go:171","msg":"trace[1948044305] linearizableReadLoop","detail":"{readStateIndex:5208; appliedIndex:5208; }","duration":"149.997961ms","start":"2026-03-01T21:20:10.513715Z","end":"2026-03-01T21:20:10.663713Z","steps":["trace[1948044305] 'read index received' (duration: 149.984241ms)","trace[1948044305] 'applied index is now lower than readState.Index' (duration: 10.29µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:20:10.664125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.381859ms","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-01T21:20:10.664169Z","caller":"traceutil/trace.go:171","msg":"trace[1613528901] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5009; }","duration":"150.44432ms","start":"2026-03-01T21:20:10.513708Z","end":"2026-03-01T21:20:10.664152Z","steps":["trace[1613528901] 'agreement among raft nodes before linearized reading' (duration: 150.155774ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:11.31557Z","caller":"traceutil/trace.go:171","msg":"trace[1277689399] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"590.127358ms","start":"2026-03-01T21:20:10.725409Z","end":"2026-03-01T21:20:11.315536Z","steps":["trace[1277689399] 'process raft request' (duration: 589.889583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:11.315837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.725388Z","time spent":"590.301931ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:20:11.498004Z","caller":"traceutil/trace.go:171","msg":"trace[640627509] linearizableReadLoop","detail":"{readStateIndex:5211; appliedIndex:5209; }","duration":"676.501398ms","start":"2026-03-01T21:20:10.82148Z","end":"2026-03-01T21:20:11.497982Z","steps":["trace[640627509] 'read index received' (duration: 495.321389ms)","trace[640627509] 'applied index is now lower than readState.Index' (duration: 181.178639ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:20:11.498051Z","caller":"traceutil/trace.go:171","msg":"trace[449029284] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"745.591746ms","start":"2026-03-01T21:20:10.752435Z","end":"2026-03-01T21:20:11.498027Z","steps":["trace[449029284] 'process raft request' (duration: 745.453543ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:11.498058Z","caller":"traceutil/trace.go:171","msg":"trace[172527006] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"771.713534ms","start":"2026-03-01T21:20:10.726286Z","end":"2026-03-01T21:20:11.497999Z","steps":["trace[172527006] 'process raft request' (duration: 767.131473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:11.498175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.752419Z","time spent":"745.678608ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:20:11.498238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.726266Z","time spent":"771.865007ms","remote":"127.0.0.1:46698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7599,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:20:11.498338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.560375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-01T21:20:11.498417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.930466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:20:11.498458Z","caller":"traceutil/trace.go:171","msg":"trace[1756028372] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:5013; }","duration":"676.974707ms","start":"2026-03-01T21:20:10.821472Z","end":"2026-03-01T21:20:11.498447Z","steps":["trace[1756028372] 'agreement among raft nodes before linearized reading' (duration: 676.6341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:11.498475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.508063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:20:11.498501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.821431Z","time spent":"677.060089ms","remote":"127.0.0.1:41006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":53,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-03-01T21:20:11.498508Z","caller":"traceutil/trace.go:171","msg":"trace[2127690328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5013; }","duration":"197.614274ms","start":"2026-03-01T21:20:11.300886Z","end":"2026-03-01T21:20:11.4985Z","steps":["trace[2127690328] 'agreement among raft nodes before linearized reading' (duration: 197.558573ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:11.49842Z","caller":"traceutil/trace.go:171","msg":"trace[2038554270] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:5013; }","duration":"648.681737ms","start":"2026-03-01T21:20:10.849721Z","end":"2026-03-01T21:20:11.498403Z","steps":["trace[2038554270] 'agreement among raft nodes before linearized reading' (duration: 648.458013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:11.498633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:20:10.849701Z","time spent":"648.908721ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-03-01T21:20:49.401708Z","caller":"traceutil/trace.go:171","msg":"trace[288532237] linearizableReadLoop","detail":"{readStateIndex:5517; appliedIndex:5516; }","duration":"100.335976ms","start":"2026-03-01T21:20:49.301338Z","end":"2026-03-01T21:20:49.401674Z","steps":["trace[288532237] 'read index received' (duration: 99.993579ms)","trace[288532237] 'applied index is now lower than readState.Index' (duration: 341.237µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:20:49.401847Z","caller":"traceutil/trace.go:171","msg":"trace[1439934208] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"151.268159ms","start":"2026-03-01T21:20:49.25055Z","end":"2026-03-01T21:20:49.401818Z","steps":["trace[1439934208] 'process raft request' (duration: 150.893832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:20:49.40212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.530009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:20:49.40219Z","caller":"traceutil/trace.go:171","msg":"trace[1656634176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5309; }","duration":"100.924108ms","start":"2026-03-01T21:20:49.301249Z","end":"2026-03-01T21:20:49.402173Z","steps":["trace[1656634176] 'agreement among raft nodes before linearized reading' (duration: 100.561921ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:49.985947Z","caller":"traceutil/trace.go:171","msg":"trace[460893767] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"136.464518ms","start":"2026-03-01T21:20:49.849456Z","end":"2026-03-01T21:20:49.98592Z","steps":["trace[460893767] 'process raft request' (duration: 53.857831ms)","trace[460893767] 'compare' (duration: 82.350872ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:20:49.986255Z","caller":"traceutil/trace.go:171","msg":"trace[383696614] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"136.702362ms","start":"2026-03-01T21:20:49.849527Z","end":"2026-03-01T21:20:49.98623Z","steps":["trace[383696614] 'process raft request' (duration: 136.61198ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:20:49.986273Z","caller":"traceutil/trace.go:171","msg":"trace[1374519709] transaction","detail":"{read_only:false; response_revision:5315; number_of_response:1; }","duration":"136.789883ms","start":"2026-03-01T21:20:49.849461Z","end":"2026-03-01T21:20:49.986251Z","steps":["trace[1374519709] 'process raft request' (duration: 136.420906ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:21:27.717881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2724} {"level":"info","ts":"2026-03-01T21:21:27.783228Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2724,"took":"63.317316ms","hash":1961755667,"current-db-size-bytes":40714240,"current-db-size":"41 MB","current-db-size-in-use-bytes":30277632,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-01T21:21:27.783306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1961755667,"revision":2724,"compact-revision":-1} {"level":"info","ts":"2026-03-01T21:26:27.723908Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5614} {"level":"info","ts":"2026-03-01T21:26:27.814827Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5614,"took":"88.652275ms","hash":2560124169,"current-db-size-bytes":41881600,"current-db-size":"42 MB","current-db-size-in-use-bytes":21344256,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-03-01T21:26:27.814894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2560124169,"revision":5614,"compact-revision":2724} {"level":"info","ts":"2026-03-01T21:26:49.822057Z","caller":"traceutil/trace.go:171","msg":"trace[803749277] transaction","detail":"{read_only:false; response_revision:7817; number_of_response:1; }","duration":"116.576158ms","start":"2026-03-01T21:26:49.705447Z","end":"2026-03-01T21:26:49.822024Z","steps":["trace[803749277] 'process raft request' (duration: 58.776934ms)","trace[803749277] 'compare' (duration: 57.668592ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:26:49.993157Z","caller":"traceutil/trace.go:171","msg":"trace[65545024] transaction","detail":"{read_only:false; response_revision:7820; number_of_response:1; }","duration":"153.143361ms","start":"2026-03-01T21:26:49.839987Z","end":"2026-03-01T21:26:49.99313Z","steps":["trace[65545024] 'process raft request' (duration: 84.594245ms)","trace[65545024] 'compare' (duration: 68.334762ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:26:51.072696Z","caller":"traceutil/trace.go:171","msg":"trace[1500193181] linearizableReadLoop","detail":"{readStateIndex:8119; appliedIndex:8118; }","duration":"375.332712ms","start":"2026-03-01T21:26:50.697338Z","end":"2026-03-01T21:26:51.072671Z","steps":["trace[1500193181] 'read index received' (duration: 374.890722ms)","trace[1500193181] 'applied index is now lower than readState.Index' (duration: 440.879µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:26:51.072726Z","caller":"traceutil/trace.go:171","msg":"trace[258123586] transaction","detail":"{read_only:false; response_revision:7824; number_of_response:1; }","duration":"474.017477ms","start":"2026-03-01T21:26:50.598679Z","end":"2026-03-01T21:26:51.072696Z","steps":["trace[258123586] 'process raft request' (duration: 473.639929ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:51.072892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:50.598648Z","time spent":"474.151799ms","remote":"127.0.0.1:33564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414406,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:26:51.072926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.576947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-01T21:26:51.072962Z","caller":"traceutil/trace.go:171","msg":"trace[2027391881] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7824; }","duration":"375.671438ms","start":"2026-03-01T21:26:50.69728Z","end":"2026-03-01T21:26:51.072952Z","steps":["trace[2027391881] 'agreement among raft nodes before linearized reading' (duration: 375.509105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:51.073007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:50.697254Z","time spent":"375.74218ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-01T21:26:51.26345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.453462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:26:51.263748Z","caller":"traceutil/trace.go:171","msg":"trace[1085497853] linearizableReadLoop","detail":"{readStateIndex:8121; appliedIndex:8119; }","duration":"190.942291ms","start":"2026-03-01T21:26:51.072763Z","end":"2026-03-01T21:26:51.263706Z","steps":["trace[1085497853] 'read index received' (duration: 53.987169ms)","trace[1085497853] 'applied index is now lower than readState.Index' (duration: 136.954072ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:26:51.263742Z","caller":"traceutil/trace.go:171","msg":"trace[2019771393] transaction","detail":"{read_only:false; response_revision:7825; number_of_response:1; }","duration":"286.144376ms","start":"2026-03-01T21:26:50.977567Z","end":"2026-03-01T21:26:51.263711Z","steps":["trace[2019771393] 'process raft request' (duration: 149.239535ms)","trace[2019771393] 'compare' (duration: 136.257267ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:26:51.264065Z","caller":"traceutil/trace.go:171","msg":"trace[1902064815] transaction","detail":"{read_only:false; response_revision:7826; number_of_response:1; }","duration":"195.43249ms","start":"2026-03-01T21:26:51.068603Z","end":"2026-03-01T21:26:51.264036Z","steps":["trace[1902064815] 'process raft request' (duration: 195.000202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:51.264125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.597983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:26:51.264168Z","caller":"traceutil/trace.go:171","msg":"trace[2088214490] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:7826; }","duration":"247.661455ms","start":"2026-03-01T21:26:51.016495Z","end":"2026-03-01T21:26:51.264156Z","steps":["trace[2088214490] 'agreement among raft nodes before linearized reading' (duration: 247.555463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:51.264144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.465462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-03-01T21:26:51.264137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.484012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-01T21:26:51.264215Z","caller":"traceutil/trace.go:171","msg":"trace[994569209] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7826; }","duration":"187.572434ms","start":"2026-03-01T21:26:51.076628Z","end":"2026-03-01T21:26:51.264201Z","steps":["trace[994569209] 'agreement among raft nodes before linearized reading' (duration: 187.296509ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:26:51.264247Z","caller":"traceutil/trace.go:171","msg":"trace[1520953129] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:7826; }","duration":"391.665896ms","start":"2026-03-01T21:26:50.872555Z","end":"2026-03-01T21:26:51.264221Z","steps":["trace[1520953129] 'agreement among raft nodes before linearized reading' (duration: 391.303139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:51.264321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:50.872539Z","time spent":"391.769048ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-03-01T21:26:51.94256Z","caller":"traceutil/trace.go:171","msg":"trace[1978112676] linearizableReadLoop","detail":"{readStateIndex:8126; appliedIndex:8125; }","duration":"116.227781ms","start":"2026-03-01T21:26:51.8263Z","end":"2026-03-01T21:26:51.942527Z","steps":["trace[1978112676] 'read index received' (duration: 20.858352ms)","trace[1978112676] 'applied index is now lower than readState.Index' (duration: 95.367709ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:26:51.942582Z","caller":"traceutil/trace.go:171","msg":"trace[43403747] transaction","detail":"{read_only:false; response_revision:7830; number_of_response:1; }","duration":"231.710128ms","start":"2026-03-01T21:26:51.710843Z","end":"2026-03-01T21:26:51.942553Z","steps":["trace[43403747] 'process raft request' (duration: 136.34777ms)","trace[43403747] 'compare' (duration: 95.114824ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:26:51.942946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.617818ms","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-01T21:26:51.943007Z","caller":"traceutil/trace.go:171","msg":"trace[1566723963] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7830; }","duration":"116.772162ms","start":"2026-03-01T21:26:51.82622Z","end":"2026-03-01T21:26:51.942992Z","steps":["trace[1566723963] 'agreement among raft nodes before linearized reading' (duration: 116.406045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:52.327443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.448988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:26:52.327599Z","caller":"traceutil/trace.go:171","msg":"trace[1241650729] transaction","detail":"{read_only:false; response_revision:7831; number_of_response:1; }","duration":"379.81946ms","start":"2026-03-01T21:26:51.947759Z","end":"2026-03-01T21:26:52.327578Z","steps":["trace[1241650729] 'process raft request' (duration: 226.137748ms)","trace[1241650729] 'compare' (duration: 153.292095ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:26:52.327659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:51.947732Z","time spent":"379.899542ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:26:52.327709Z","caller":"traceutil/trace.go:171","msg":"trace[1672648722] transaction","detail":"{read_only:false; response_revision:7832; number_of_response:1; }","duration":"328.077497ms","start":"2026-03-01T21:26:51.999614Z","end":"2026-03-01T21:26:52.327691Z","steps":["trace[1672648722] 'process raft request' (duration: 327.984985ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:26:52.327681Z","caller":"traceutil/trace.go:171","msg":"trace[1069421130] linearizableReadLoop","detail":"{readStateIndex:8127; appliedIndex:8126; }","duration":"330.657688ms","start":"2026-03-01T21:26:51.996997Z","end":"2026-03-01T21:26:52.327654Z","steps":["trace[1069421130] 'read index received' (duration: 176.993125ms)","trace[1069421130] 'applied index is now lower than readState.Index' (duration: 153.661903ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:26:52.327993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.992925ms","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-01T21:26:52.328063Z","caller":"traceutil/trace.go:171","msg":"trace[1459670029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7832; }","duration":"331.106307ms","start":"2026-03-01T21:26:51.996939Z","end":"2026-03-01T21:26:52.328046Z","steps":["trace[1459670029] 'agreement among raft nodes before linearized reading' (duration: 330.826311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:52.32809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:51.999596Z","time spent":"328.183929ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:26:52.328119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:51.996917Z","time spent":"331.189219ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T21:26:52.328346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.756219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-01T21:26:52.32838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.762999ms","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-01T21:26:52.328442Z","caller":"traceutil/trace.go:171","msg":"trace[1536943250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7832; }","duration":"330.907642ms","start":"2026-03-01T21:26:51.997511Z","end":"2026-03-01T21:26:52.328419Z","steps":["trace[1536943250] 'agreement among raft nodes before linearized reading' (duration: 330.572505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:52.32863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:51.997489Z","time spent":"331.098856ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-01T21:26:52.328497Z","caller":"traceutil/trace.go:171","msg":"trace[585049063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7832; }","duration":"330.913423ms","start":"2026-03-01T21:26:51.997547Z","end":"2026-03-01T21:26:52.32846Z","steps":["trace[585049063] 'agreement among raft nodes before linearized reading' (duration: 330.605807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:52.328743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:26:51.997528Z","time spent":"331.190208ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T21:26:52.453192Z","caller":"traceutil/trace.go:171","msg":"trace[519575839] transaction","detail":"{read_only:false; response_revision:7835; number_of_response:1; }","duration":"118.597099ms","start":"2026-03-01T21:26:52.334569Z","end":"2026-03-01T21:26:52.453166Z","steps":["trace[519575839] 'process raft request' (duration: 118.496437ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:26:52.453213Z","caller":"traceutil/trace.go:171","msg":"trace[1062103131] transaction","detail":"{read_only:false; response_revision:7834; number_of_response:1; }","duration":"118.616258ms","start":"2026-03-01T21:26:52.334522Z","end":"2026-03-01T21:26:52.453138Z","steps":["trace[1062103131] 'process raft request' (duration: 113.66617ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:26:52.45345Z","caller":"traceutil/trace.go:171","msg":"trace[193345064] transaction","detail":"{read_only:false; response_revision:7836; number_of_response:1; }","duration":"118.769922ms","start":"2026-03-01T21:26:52.334666Z","end":"2026-03-01T21:26:52.453435Z","steps":["trace[193345064] 'process raft request' (duration: 118.452746ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:26:53.375976Z","caller":"traceutil/trace.go:171","msg":"trace[1522323292] transaction","detail":"{read_only:false; response_revision:7838; number_of_response:1; }","duration":"106.472668ms","start":"2026-03-01T21:26:53.269447Z","end":"2026-03-01T21:26:53.375919Z","steps":["trace[1522323292] 'process raft request' (duration: 105.753483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:26:54.29733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.098555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-9r26s\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-03-01T21:26:54.297426Z","caller":"traceutil/trace.go:171","msg":"trace[1155397497] range","detail":"{range_begin:/registry/pods/rook-ceph/rook-ceph-operator-76bbbb77ff-9r26s; range_end:; response_count:1; response_revision:7841; }","duration":"185.254078ms","start":"2026-03-01T21:26:54.112149Z","end":"2026-03-01T21:26:54.297403Z","steps":["trace[1155397497] 'range keys from in-memory index tree' (duration: 184.862611ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:12.504131Z","caller":"traceutil/trace.go:171","msg":"trace[1488248005] transaction","detail":"{read_only:false; response_revision:7935; number_of_response:1; }","duration":"117.347306ms","start":"2026-03-01T21:27:12.386756Z","end":"2026-03-01T21:27:12.504103Z","steps":["trace[1488248005] 'process raft request' (duration: 29.780621ms)","trace[1488248005] 'compare' (duration: 87.457283ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:27:17.574043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.981238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19434"} {"level":"info","ts":"2026-03-01T21:27:17.574219Z","caller":"traceutil/trace.go:171","msg":"trace[1961337682] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:8005; }","duration":"180.169682ms","start":"2026-03-01T21:27:17.393966Z","end":"2026-03-01T21:27:17.574136Z","steps":["trace[1961337682] 'range keys from in-memory index tree' (duration: 179.711093ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:17.580902Z","caller":"traceutil/trace.go:171","msg":"trace[1845652795] transaction","detail":"{read_only:false; response_revision:8006; number_of_response:1; }","duration":"126.861254ms","start":"2026-03-01T21:27:17.454012Z","end":"2026-03-01T21:27:17.580873Z","steps":["trace[1845652795] 'process raft request' (duration: 126.695581ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:17.583816Z","caller":"traceutil/trace.go:171","msg":"trace[1109974626] transaction","detail":"{read_only:false; response_revision:8007; number_of_response:1; }","duration":"125.871864ms","start":"2026-03-01T21:27:17.457901Z","end":"2026-03-01T21:27:17.583773Z","steps":["trace[1109974626] 'process raft request' (duration: 125.730572ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:20.351934Z","caller":"traceutil/trace.go:171","msg":"trace[1711334500] linearizableReadLoop","detail":"{readStateIndex:8321; appliedIndex:8320; }","duration":"436.613064ms","start":"2026-03-01T21:27:19.915296Z","end":"2026-03-01T21:27:20.351909Z","steps":["trace[1711334500] 'read index received' (duration: 436.38914ms)","trace[1711334500] 'applied index is now lower than readState.Index' (duration: 222.984µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:27:20.352293Z","caller":"traceutil/trace.go:171","msg":"trace[154080870] transaction","detail":"{read_only:false; response_revision:8018; number_of_response:1; }","duration":"451.866035ms","start":"2026-03-01T21:27:19.900413Z","end":"2026-03-01T21:27:20.352279Z","steps":["trace[154080870] 'process raft request' (duration: 451.334275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.35242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:19.900389Z","time spent":"451.946007ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:27:20.35273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.437831ms","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-01T21:27:20.352777Z","caller":"traceutil/trace.go:171","msg":"trace[1454280814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8018; }","duration":"437.521373ms","start":"2026-03-01T21:27:19.915243Z","end":"2026-03-01T21:27:20.352764Z","steps":["trace[1454280814] 'agreement among raft nodes before linearized reading' (duration: 437.38844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.352822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:19.915202Z","time spent":"437.609894ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-01T21:27:20.353011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.543615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:27:20.353055Z","caller":"traceutil/trace.go:171","msg":"trace[1228916062] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8018; }","duration":"311.622997ms","start":"2026-03-01T21:27:20.04142Z","end":"2026-03-01T21:27:20.353043Z","steps":["trace[1228916062] 'agreement among raft nodes before linearized reading' (duration: 311.556616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.353104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.0414Z","time spent":"311.688798ms","remote":"127.0.0.1:40976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:27:20.353417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.368773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:27:20.353487Z","caller":"traceutil/trace.go:171","msg":"trace[646091986] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8018; }","duration":"191.480904ms","start":"2026-03-01T21:27:20.161989Z","end":"2026-03-01T21:27:20.35347Z","steps":["trace[646091986] 'agreement among raft nodes before linearized reading' (duration: 191.351912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.860172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644775672667523,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T21:27:20.967538Z","caller":"traceutil/trace.go:171","msg":"trace[2006245511] transaction","detail":"{read_only:false; response_revision:8019; number_of_response:1; }","duration":"608.615472ms","start":"2026-03-01T21:27:20.358896Z","end":"2026-03-01T21:27:20.967512Z","steps":["trace[2006245511] 'process raft request' (duration: 605.207445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.968852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.35888Z","time spent":"608.726744ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:27:20.969416Z","caller":"traceutil/trace.go:171","msg":"trace[1449903448] transaction","detail":"{read_only:false; response_revision:8020; number_of_response:1; }","duration":"436.789857ms","start":"2026-03-01T21:27:20.532604Z","end":"2026-03-01T21:27:20.969394Z","steps":["trace[1449903448] 'process raft request' (duration: 436.650814ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:20.969526Z","caller":"traceutil/trace.go:171","msg":"trace[1673560571] linearizableReadLoop","detail":"{readStateIndex:8324; appliedIndex:8321; }","duration":"610.03754ms","start":"2026-03-01T21:27:20.359436Z","end":"2026-03-01T21:27:20.969474Z","steps":["trace[1673560571] 'read index received' (duration: 604.822067ms)","trace[1673560571] 'applied index is now lower than readState.Index' (duration: 5.214283ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:27:20.969559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.532578Z","time spent":"436.91237ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:27:20.969679Z","caller":"traceutil/trace.go:171","msg":"trace[56442161] transaction","detail":"{read_only:false; response_revision:8021; number_of_response:1; }","duration":"351.258002ms","start":"2026-03-01T21:27:20.618413Z","end":"2026-03-01T21:27:20.969671Z","steps":["trace[56442161] 'process raft request' (duration: 350.945796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.969761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.618395Z","time spent":"351.333543ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:27:20.96987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.681818ms","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-01T21:27:20.969922Z","caller":"traceutil/trace.go:171","msg":"trace[1380992909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8021; }","duration":"422.773139ms","start":"2026-03-01T21:27:20.547132Z","end":"2026-03-01T21:27:20.969906Z","steps":["trace[1380992909] 'agreement among raft nodes before linearized reading' (duration: 422.610856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.969956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.54711Z","time spent":"422.838831ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-01T21:27:20.969952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.272694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:27:20.970019Z","caller":"traceutil/trace.go:171","msg":"trace[2093308507] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:8021; }","duration":"229.383347ms","start":"2026-03-01T21:27:20.740621Z","end":"2026-03-01T21:27:20.970005Z","steps":["trace[2093308507] 'agreement among raft nodes before linearized reading' (duration: 229.225014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.970114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.655115ms","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-01T21:27:20.970169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.037586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:27:20.970168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.695894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:27:20.970209Z","caller":"traceutil/trace.go:171","msg":"trace[36697989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8021; }","duration":"370.769298ms","start":"2026-03-01T21:27:20.59942Z","end":"2026-03-01T21:27:20.970189Z","steps":["trace[36697989] 'agreement among raft nodes before linearized reading' (duration: 370.522833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.97021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.628249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mnw6m\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-01T21:27:20.970233Z","caller":"traceutil/trace.go:171","msg":"trace[639716991] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8021; }","duration":"133.137459ms","start":"2026-03-01T21:27:20.837078Z","end":"2026-03-01T21:27:20.970215Z","steps":["trace[639716991] 'agreement among raft nodes before linearized reading' (duration: 133.039147ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:20.970253Z","caller":"traceutil/trace.go:171","msg":"trace[1264847069] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mnw6m; range_end:; response_count:1; response_revision:8021; }","duration":"350.68107ms","start":"2026-03-01T21:27:20.619563Z","end":"2026-03-01T21:27:20.970244Z","steps":["trace[1264847069] 'agreement among raft nodes before linearized reading' (duration: 350.570068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.970258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.599415Z","time spent":"370.833519ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-01T21:27:20.970279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.619557Z","time spent":"350.716581ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-mnw6m\" "} {"level":"info","ts":"2026-03-01T21:27:20.970263Z","caller":"traceutil/trace.go:171","msg":"trace[590709461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8021; }","duration":"610.844476ms","start":"2026-03-01T21:27:20.359394Z","end":"2026-03-01T21:27:20.970239Z","steps":["trace[590709461] 'agreement among raft nodes before linearized reading' (duration: 610.698413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.970315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.586067ms","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-01T21:27:20.970374Z","caller":"traceutil/trace.go:171","msg":"trace[576164482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8021; }","duration":"415.715839ms","start":"2026-03-01T21:27:20.55465Z","end":"2026-03-01T21:27:20.970366Z","steps":["trace[576164482] 'agreement among raft nodes before linearized reading' (duration: 415.491874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.970356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.359374Z","time spent":"610.967409ms","remote":"127.0.0.1:40970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T21:27:20.970434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.554639Z","time spent":"415.768909ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T21:27:20.971606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.984982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-01T21:27:20.971667Z","caller":"traceutil/trace.go:171","msg":"trace[799764471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8021; }","duration":"374.073175ms","start":"2026-03-01T21:27:20.59758Z","end":"2026-03-01T21:27:20.971653Z","steps":["trace[799764471] 'agreement among raft nodes before linearized reading' (duration: 372.474593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:20.971712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:27:20.597562Z","time spent":"374.140086ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-01T21:27:34.735543Z","caller":"traceutil/trace.go:171","msg":"trace[916734822] linearizableReadLoop","detail":"{readStateIndex:8438; appliedIndex:8437; }","duration":"112.268574ms","start":"2026-03-01T21:27:34.62324Z","end":"2026-03-01T21:27:34.735508Z","steps":["trace[916734822] 'read index received' (duration: 112.05455ms)","trace[916734822] 'applied index is now lower than readState.Index' (duration: 213.124µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:27:34.73562Z","caller":"traceutil/trace.go:171","msg":"trace[34928636] transaction","detail":"{read_only:false; response_revision:8130; number_of_response:1; }","duration":"157.005981ms","start":"2026-03-01T21:27:34.57859Z","end":"2026-03-01T21:27:34.735596Z","steps":["trace[34928636] 'process raft request' (duration: 156.774557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:34.735736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.486659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-03-01T21:27:34.735775Z","caller":"traceutil/trace.go:171","msg":"trace[901024748] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8130; }","duration":"112.56951ms","start":"2026-03-01T21:27:34.623194Z","end":"2026-03-01T21:27:34.735763Z","steps":["trace[901024748] 'agreement among raft nodes before linearized reading' (duration: 112.421687ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:35.507208Z","caller":"traceutil/trace.go:171","msg":"trace[1453446405] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"143.30063ms","start":"2026-03-01T21:27:35.363883Z","end":"2026-03-01T21:27:35.507184Z","steps":["trace[1453446405] 'process raft request' (duration: 143.150877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:47.276535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.543044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ceph-object-controller-detect-version-6cslt\" ","response":"range_response_count:1 size:4559"} {"level":"info","ts":"2026-03-01T21:27:47.276618Z","caller":"traceutil/trace.go:171","msg":"trace[93465915] range","detail":"{range_begin:/registry/pods/openstack/ceph-object-controller-detect-version-6cslt; range_end:; response_count:1; response_revision:8223; }","duration":"263.671706ms","start":"2026-03-01T21:27:47.012928Z","end":"2026-03-01T21:27:47.2766Z","steps":["trace[93465915] 'range keys from in-memory index tree' (duration: 263.37793ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:47.276982Z","caller":"traceutil/trace.go:171","msg":"trace[2056275833] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"181.853664ms","start":"2026-03-01T21:27:47.095095Z","end":"2026-03-01T21:27:47.276949Z","steps":["trace[2056275833] 'process raft request' (duration: 181.67607ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:47.277354Z","caller":"traceutil/trace.go:171","msg":"trace[371367464] linearizableReadLoop","detail":"{readStateIndex:8537; appliedIndex:8537; }","duration":"165.242195ms","start":"2026-03-01T21:27:47.11209Z","end":"2026-03-01T21:27:47.277333Z","steps":["trace[371367464] 'read index received' (duration: 165.228535ms)","trace[371367464] 'applied index is now lower than readState.Index' (duration: 5.88µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:27:47.277588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.906609ms","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-01T21:27:47.277624Z","caller":"traceutil/trace.go:171","msg":"trace[1792590554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8224; }","duration":"161.990771ms","start":"2026-03-01T21:27:47.115626Z","end":"2026-03-01T21:27:47.277617Z","steps":["trace[1792590554] 'agreement among raft nodes before linearized reading' (duration: 161.812817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:47.277659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.058666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:27:47.277736Z","caller":"traceutil/trace.go:171","msg":"trace[31969402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8224; }","duration":"138.162238ms","start":"2026-03-01T21:27:47.139554Z","end":"2026-03-01T21:27:47.277716Z","steps":["trace[31969402] 'agreement among raft nodes before linearized reading' (duration: 137.965814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:47.277676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.224359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-01T21:27:47.277775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.122088ms","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-01T21:27:47.277753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.608392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T21:27:47.277817Z","caller":"traceutil/trace.go:171","msg":"trace[617587198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8224; }","duration":"141.383931ms","start":"2026-03-01T21:27:47.13642Z","end":"2026-03-01T21:27:47.277804Z","steps":["trace[617587198] 'agreement among raft nodes before linearized reading' (duration: 141.182758ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:47.277861Z","caller":"traceutil/trace.go:171","msg":"trace[2146188651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8224; }","duration":"137.22614ms","start":"2026-03-01T21:27:47.140612Z","end":"2026-03-01T21:27:47.277838Z","steps":["trace[2146188651] 'agreement among raft nodes before linearized reading' (duration: 137.023536ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:27:47.277907Z","caller":"traceutil/trace.go:171","msg":"trace[685683414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8224; }","duration":"165.816116ms","start":"2026-03-01T21:27:47.112033Z","end":"2026-03-01T21:27:47.277849Z","steps":["trace[685683414] 'agreement among raft nodes before linearized reading' (duration: 165.370748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:47.27808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.647494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/pvc-ceph-client-key\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:27:47.278137Z","caller":"traceutil/trace.go:171","msg":"trace[1478098144] range","detail":"{range_begin:/registry/secrets/openstack/pvc-ceph-client-key; range_end:; response_count:0; response_revision:8224; }","duration":"101.750306ms","start":"2026-03-01T21:27:47.17637Z","end":"2026-03-01T21:27:47.278121Z","steps":["trace[1478098144] 'agreement among raft nodes before linearized reading' (duration: 101.226595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:27:47.278224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.795921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mnw6m\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-01T21:27:47.278749Z","caller":"traceutil/trace.go:171","msg":"trace[1582290919] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mnw6m; range_end:; response_count:1; response_revision:8224; }","duration":"133.331212ms","start":"2026-03-01T21:27:47.145404Z","end":"2026-03-01T21:27:47.278736Z","steps":["trace[1582290919] 'agreement among raft nodes before linearized reading' (duration: 132.074467ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:28:35.702262Z","caller":"traceutil/trace.go:171","msg":"trace[29770489] transaction","detail":"{read_only:false; response_revision:8754; number_of_response:1; }","duration":"227.537675ms","start":"2026-03-01T21:28:35.474697Z","end":"2026-03-01T21:28:35.702234Z","steps":["trace[29770489] 'process raft request' (duration: 227.340631ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:28:35.71174Z","caller":"traceutil/trace.go:171","msg":"trace[1281273013] linearizableReadLoop","detail":"{readStateIndex:9080; appliedIndex:9078; }","duration":"181.295449ms","start":"2026-03-01T21:28:35.530418Z","end":"2026-03-01T21:28:35.711714Z","steps":["trace[1281273013] 'read index received' (duration: 171.691529ms)","trace[1281273013] 'applied index is now lower than readState.Index' (duration: 9.60268ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:28:35.711791Z","caller":"traceutil/trace.go:171","msg":"trace[303994196] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"204.53891ms","start":"2026-03-01T21:28:35.507232Z","end":"2026-03-01T21:28:35.711771Z","steps":["trace[303994196] 'process raft request' (duration: 204.298946ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:28:35.711951Z","caller":"traceutil/trace.go:171","msg":"trace[1838446818] transaction","detail":"{read_only:false; response_revision:8756; number_of_response:1; }","duration":"157.024379ms","start":"2026-03-01T21:28:35.554893Z","end":"2026-03-01T21:28:35.711917Z","steps":["trace[1838446818] 'process raft request' (duration: 156.774324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:28:35.712185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.748489ms","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-01T21:28:35.712234Z","caller":"traceutil/trace.go:171","msg":"trace[1053681305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8756; }","duration":"181.847721ms","start":"2026-03-01T21:28:35.530372Z","end":"2026-03-01T21:28:35.71222Z","steps":["trace[1053681305] 'agreement among raft nodes before linearized reading' (duration: 181.647097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:28:35.712341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.427187ms","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-01T21:28:35.712362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.417796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:28:35.712373Z","caller":"traceutil/trace.go:171","msg":"trace[1877147992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8756; }","duration":"163.484288ms","start":"2026-03-01T21:28:35.548882Z","end":"2026-03-01T21:28:35.712366Z","steps":["trace[1877147992] 'agreement among raft nodes before linearized reading' (duration: 163.406426ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:28:35.712392Z","caller":"traceutil/trace.go:171","msg":"trace[621648757] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:8756; }","duration":"118.478297ms","start":"2026-03-01T21:28:35.593903Z","end":"2026-03-01T21:28:35.712381Z","steps":["trace[621648757] 'agreement among raft nodes before linearized reading' (duration: 118.423126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:28:35.712465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.123988ms","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-01T21:28:35.7125Z","caller":"traceutil/trace.go:171","msg":"trace[1429115124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8756; }","duration":"176.191249ms","start":"2026-03-01T21:28:35.536303Z","end":"2026-03-01T21:28:35.712494Z","steps":["trace[1429115124] 'agreement among raft nodes before linearized reading' (duration: 175.924054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:28:35.712561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.057088ms","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-01T21:28:35.712685Z","caller":"traceutil/trace.go:171","msg":"trace[1730875310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8756; }","duration":"169.193821ms","start":"2026-03-01T21:28:35.543461Z","end":"2026-03-01T21:28:35.712655Z","steps":["trace[1730875310] 'agreement among raft nodes before linearized reading' (duration: 168.842084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:28:35.712581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.147755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mnw6m\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-01T21:28:35.712738Z","caller":"traceutil/trace.go:171","msg":"trace[731685370] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mnw6m; range_end:; response_count:1; response_revision:8756; }","duration":"142.348089ms","start":"2026-03-01T21:28:35.570376Z","end":"2026-03-01T21:28:35.712724Z","steps":["trace[731685370] 'agreement among raft nodes before linearized reading' (duration: 141.977052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:28:35.712745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.308411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-01T21:28:35.712875Z","caller":"traceutil/trace.go:171","msg":"trace[1424292065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8756; }","duration":"174.510085ms","start":"2026-03-01T21:28:35.538325Z","end":"2026-03-01T21:28:35.712835Z","steps":["trace[1424292065] 'agreement among raft nodes before linearized reading' (duration: 174.021956ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:30:51.139059Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f52f30d5714009d5","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-01T21:30:51.151846Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-01T21:30:51.151954Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-01T21:31:27.730186Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7586} {"level":"info","ts":"2026-03-01T21:31:27.791403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7586,"took":"58.881596ms","hash":3120181248,"current-db-size-bytes":41881600,"current-db-size":"42 MB","current-db-size-in-use-bytes":25931776,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-01T21:31:27.791552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3120181248,"revision":7586,"compact-revision":5614} {"level":"warn","ts":"2026-03-01T21:32:06.220817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.727671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-03-01T21:32:06.220881Z","caller":"traceutil/trace.go:171","msg":"trace[181913934] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:10430; }","duration":"297.832002ms","start":"2026-03-01T21:32:05.923033Z","end":"2026-03-01T21:32:06.220865Z","steps":["trace[181913934] 'agreement among raft nodes before linearized reading' (duration: 297.624038ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:32:06.220556Z","caller":"traceutil/trace.go:171","msg":"trace[12085713] linearizableReadLoop","detail":"{readStateIndex:10809; appliedIndex:10808; }","duration":"297.453485ms","start":"2026-03-01T21:32:05.923077Z","end":"2026-03-01T21:32:06.22053Z","steps":["trace[12085713] 'read index received' (duration: 7.147381ms)","trace[12085713] 'applied index is now lower than readState.Index' (duration: 290.304604ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:32:06.221282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.862356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:06.221321Z","caller":"traceutil/trace.go:171","msg":"trace[1011778201] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10430; }","duration":"189.943428ms","start":"2026-03-01T21:32:06.031368Z","end":"2026-03-01T21:32:06.221312Z","steps":["trace[1011778201] 'agreement among raft nodes before linearized reading' (duration: 189.883557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:06.221395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.886246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:06.221438Z","caller":"traceutil/trace.go:171","msg":"trace[1609558187] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10430; }","duration":"189.953788ms","start":"2026-03-01T21:32:06.031474Z","end":"2026-03-01T21:32:06.221428Z","steps":["trace[1609558187] 'agreement among raft nodes before linearized reading' (duration: 189.874977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:06.22149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.428414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:32:06.221508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.569786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:32:06.221523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.412809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-03-01T21:32:06.22152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.239651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-03-01T21:32:06.221579Z","caller":"traceutil/trace.go:171","msg":"trace[690879217] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10430; }","duration":"297.572837ms","start":"2026-03-01T21:32:05.923987Z","end":"2026-03-01T21:32:06.22156Z","steps":["trace[690879217] 'agreement among raft nodes before linearized reading' (duration: 297.395123ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:32:06.221588Z","caller":"traceutil/trace.go:171","msg":"trace[1185055360] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10430; }","duration":"297.701249ms","start":"2026-03-01T21:32:05.923868Z","end":"2026-03-01T21:32:06.22157Z","steps":["trace[1185055360] 'agreement among raft nodes before linearized reading' (duration: 297.565416ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:32:06.221601Z","caller":"traceutil/trace.go:171","msg":"trace[645329865] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10430; }","duration":"183.514581ms","start":"2026-03-01T21:32:06.038064Z","end":"2026-03-01T21:32:06.221578Z","steps":["trace[645329865] 'agreement among raft nodes before linearized reading' (duration: 183.218766ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:32:06.22162Z","caller":"traceutil/trace.go:171","msg":"trace[1161155778] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10430; }","duration":"292.336783ms","start":"2026-03-01T21:32:05.929258Z","end":"2026-03-01T21:32:06.221595Z","steps":["trace[1161155778] 'agreement among raft nodes before linearized reading' (duration: 292.134709ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:06.22157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.702539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:06.221746Z","caller":"traceutil/trace.go:171","msg":"trace[131915966] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10430; }","duration":"297.893693ms","start":"2026-03-01T21:32:05.923842Z","end":"2026-03-01T21:32:06.221736Z","steps":["trace[131915966] 'agreement among raft nodes before linearized reading' (duration: 297.366023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:06.221502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.398253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:06.22182Z","caller":"traceutil/trace.go:171","msg":"trace[2053018546] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10430; }","duration":"297.791481ms","start":"2026-03-01T21:32:05.924021Z","end":"2026-03-01T21:32:06.221813Z","steps":["trace[2053018546] 'agreement among raft nodes before linearized reading' (duration: 297.334442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:06.221823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.245194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:06.222021Z","caller":"traceutil/trace.go:171","msg":"trace[1021423883] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10430; }","duration":"190.510738ms","start":"2026-03-01T21:32:06.031432Z","end":"2026-03-01T21:32:06.221942Z","steps":["trace[1021423883] 'agreement among raft nodes before linearized reading' (duration: 190.116341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:06.222022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.503069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:06.222212Z","caller":"traceutil/trace.go:171","msg":"trace[782627131] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10430; }","duration":"190.731913ms","start":"2026-03-01T21:32:06.031464Z","end":"2026-03-01T21:32:06.222196Z","steps":["trace[782627131] 'agreement among raft nodes before linearized reading' (duration: 189.807895ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:32:26.737597Z","caller":"traceutil/trace.go:171","msg":"trace[1606121045] transaction","detail":"{read_only:false; response_revision:10534; number_of_response:1; }","duration":"156.240751ms","start":"2026-03-01T21:32:26.58133Z","end":"2026-03-01T21:32:26.73757Z","steps":["trace[1606121045] 'process raft request' (duration: 156.052097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.138214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.152337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:32:27.138449Z","caller":"traceutil/trace.go:171","msg":"trace[931134567] transaction","detail":"{read_only:false; response_revision:10535; number_of_response:1; }","duration":"287.320695ms","start":"2026-03-01T21:32:26.851089Z","end":"2026-03-01T21:32:27.13841Z","steps":["trace[931134567] 'process raft request' (duration: 167.879641ms)","trace[931134567] 'compare' (duration: 118.600996ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:32:27.580349Z","caller":"traceutil/trace.go:171","msg":"trace[1420561620] linearizableReadLoop","detail":"{readStateIndex:10919; appliedIndex:10917; }","duration":"721.569665ms","start":"2026-03-01T21:32:26.858758Z","end":"2026-03-01T21:32:27.580328Z","steps":["trace[1420561620] 'read index received' (duration: 160.140248ms)","trace[1420561620] 'applied index is now lower than readState.Index' (duration: 561.428167ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:32:27.580435Z","caller":"traceutil/trace.go:171","msg":"trace[1819669496] transaction","detail":"{read_only:false; response_revision:10536; number_of_response:1; }","duration":"729.294219ms","start":"2026-03-01T21:32:26.851109Z","end":"2026-03-01T21:32:27.580403Z","steps":["trace[1819669496] 'process raft request' (duration: 729.082535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.58054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.762079ms","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-01T21:32:27.580569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:26.851081Z","time spent":"729.419531ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:32:27.580579Z","caller":"traceutil/trace.go:171","msg":"trace[1337631095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10536; }","duration":"721.843541ms","start":"2026-03-01T21:32:26.858723Z","end":"2026-03-01T21:32:27.580566Z","steps":["trace[1337631095] 'agreement among raft nodes before linearized reading' (duration: 721.714548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.580617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:26.858706Z","time spent":"721.903672ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-01T21:32:27.597586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.201447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-03-01T21:32:27.597605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.033808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mnw6m\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-01T21:32:27.597628Z","caller":"traceutil/trace.go:171","msg":"trace[1261404651] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10537; }","duration":"119.281739ms","start":"2026-03-01T21:32:27.478337Z","end":"2026-03-01T21:32:27.597619Z","steps":["trace[1261404651] 'agreement among raft nodes before linearized reading' (duration: 119.169607ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:32:27.59766Z","caller":"traceutil/trace.go:171","msg":"trace[1065776270] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mnw6m; range_end:; response_count:1; response_revision:10537; }","duration":"336.11631ms","start":"2026-03-01T21:32:27.26153Z","end":"2026-03-01T21:32:27.597646Z","steps":["trace[1065776270] 'agreement among raft nodes before linearized reading' (duration: 335.956637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.597657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.347218ms","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-01T21:32:27.597739Z","caller":"traceutil/trace.go:171","msg":"trace[1306983983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10537; }","duration":"733.464351ms","start":"2026-03-01T21:32:26.864256Z","end":"2026-03-01T21:32:27.597721Z","steps":["trace[1306983983] 'agreement among raft nodes before linearized reading' (duration: 733.221225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.597743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.537716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:27.597783Z","caller":"traceutil/trace.go:171","msg":"trace[1683535896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10537; }","duration":"298.609408ms","start":"2026-03-01T21:32:27.299164Z","end":"2026-03-01T21:32:27.597774Z","steps":["trace[1683535896] 'agreement among raft nodes before linearized reading' (duration: 298.553267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.59786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.112174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:32:27.597898Z","caller":"traceutil/trace.go:171","msg":"trace[1161074716] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:10537; }","duration":"503.207506ms","start":"2026-03-01T21:32:27.094679Z","end":"2026-03-01T21:32:27.597886Z","steps":["trace[1161074716] 'agreement among raft nodes before linearized reading' (duration: 503.132405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.597913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.679318ms","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-01T21:32:27.597932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:27.094654Z","time spent":"503.269737ms","remote":"127.0.0.1:42740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-03-01T21:32:27.597937Z","caller":"traceutil/trace.go:171","msg":"trace[1023794263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10537; }","duration":"726.746859ms","start":"2026-03-01T21:32:26.871184Z","end":"2026-03-01T21:32:27.597931Z","steps":["trace[1023794263] 'agreement among raft nodes before linearized reading' (duration: 726.684738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.597968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:26.871164Z","time spent":"726.80057ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T21:32:27.597989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.961394ms","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-01T21:32:27.597789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:26.864244Z","time spent":"733.536702ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-01T21:32:27.598059Z","caller":"traceutil/trace.go:171","msg":"trace[989823042] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10537; }","duration":"726.038475ms","start":"2026-03-01T21:32:26.871994Z","end":"2026-03-01T21:32:27.598033Z","steps":["trace[989823042] 'agreement among raft nodes before linearized reading' (duration: 725.886352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.597696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:27.261513Z","time spent":"336.174691ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-mnw6m\" "} {"level":"warn","ts":"2026-03-01T21:32:27.598094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:26.871983Z","time spent":"726.102666ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-01T21:32:27.598336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.613885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-01T21:32:27.598424Z","caller":"traceutil/trace.go:171","msg":"trace[1323411983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10537; }","duration":"727.748338ms","start":"2026-03-01T21:32:26.870654Z","end":"2026-03-01T21:32:27.598403Z","steps":["trace[1323411983] 'agreement among raft nodes before linearized reading' (duration: 727.422882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:32:27.598506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:32:26.870637Z","time spent":"727.85643ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-01T21:36:27.738534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10022} {"level":"info","ts":"2026-03-01T21:36:27.822048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10022,"took":"81.302371ms","hash":119799502,"current-db-size-bytes":41881600,"current-db-size":"42 MB","current-db-size-in-use-bytes":23445504,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-01T21:36:27.822133Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":119799502,"revision":10022,"compact-revision":7586} {"level":"info","ts":"2026-03-01T21:38:14.744633Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000003389.wal"} {"level":"info","ts":"2026-03-01T21:38:42.324818Z","caller":"traceutil/trace.go:171","msg":"trace[899374568] transaction","detail":"{read_only:false; response_revision:12884; number_of_response:1; }","duration":"136.566077ms","start":"2026-03-01T21:38:42.18821Z","end":"2026-03-01T21:38:42.324776Z","steps":["trace[899374568] 'process raft request' (duration: 136.19567ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:42.62318Z","caller":"traceutil/trace.go:171","msg":"trace[665454528] transaction","detail":"{read_only:false; response_revision:12885; number_of_response:1; }","duration":"140.827869ms","start":"2026-03-01T21:38:42.482329Z","end":"2026-03-01T21:38:42.623157Z","steps":["trace[665454528] 'process raft request' (duration: 140.670976ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:42.626066Z","caller":"traceutil/trace.go:171","msg":"trace[1567058543] transaction","detail":"{read_only:false; response_revision:12886; number_of_response:1; }","duration":"135.774202ms","start":"2026-03-01T21:38:42.490262Z","end":"2026-03-01T21:38:42.626036Z","steps":["trace[1567058543] 'process raft request' (duration: 135.579329ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:50.759136Z","caller":"traceutil/trace.go:171","msg":"trace[712826860] transaction","detail":"{read_only:false; response_revision:12923; number_of_response:1; }","duration":"150.319158ms","start":"2026-03-01T21:38:50.608789Z","end":"2026-03-01T21:38:50.759108Z","steps":["trace[712826860] 'process raft request' (duration: 149.89598ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:50.768918Z","caller":"traceutil/trace.go:171","msg":"trace[1623590255] transaction","detail":"{read_only:false; response_revision:12924; number_of_response:1; }","duration":"116.734471ms","start":"2026-03-01T21:38:50.652156Z","end":"2026-03-01T21:38:50.76889Z","steps":["trace[1623590255] 'process raft request' (duration: 116.604888ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:57.888374Z","caller":"traceutil/trace.go:171","msg":"trace[1420379396] linearizableReadLoop","detail":"{readStateIndex:13450; appliedIndex:13449; }","duration":"127.350856ms","start":"2026-03-01T21:38:57.761002Z","end":"2026-03-01T21:38:57.888353Z","steps":["trace[1420379396] 'read index received' (duration: 127.167252ms)","trace[1420379396] 'applied index is now lower than readState.Index' (duration: 182.364µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:38:57.888556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.545609ms","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-01T21:38:57.888591Z","caller":"traceutil/trace.go:171","msg":"trace[1799417942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12974; }","duration":"127.660421ms","start":"2026-03-01T21:38:57.760919Z","end":"2026-03-01T21:38:57.88858Z","steps":["trace[1799417942] 'agreement among raft nodes before linearized reading' (duration: 127.523078ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:57.888587Z","caller":"traceutil/trace.go:171","msg":"trace[604543855] transaction","detail":"{read_only:false; response_revision:12974; number_of_response:1; }","duration":"128.332926ms","start":"2026-03-01T21:38:57.760231Z","end":"2026-03-01T21:38:57.888564Z","steps":["trace[604543855] 'process raft request' (duration: 127.854666ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:57.95294Z","caller":"traceutil/trace.go:171","msg":"trace[1566327497] transaction","detail":"{read_only:false; response_revision:12975; number_of_response:1; }","duration":"189.461962ms","start":"2026-03-01T21:38:57.763449Z","end":"2026-03-01T21:38:57.952911Z","steps":["trace[1566327497] 'process raft request' (duration: 189.290368ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:57.953099Z","caller":"traceutil/trace.go:171","msg":"trace[542175555] transaction","detail":"{read_only:false; response_revision:12976; number_of_response:1; }","duration":"189.126755ms","start":"2026-03-01T21:38:57.763953Z","end":"2026-03-01T21:38:57.95308Z","steps":["trace[542175555] 'process raft request' (duration: 188.916341ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:58.764227Z","caller":"traceutil/trace.go:171","msg":"trace[1724911663] linearizableReadLoop","detail":"{readStateIndex:13454; appliedIndex:13453; }","duration":"279.324642ms","start":"2026-03-01T21:38:58.484875Z","end":"2026-03-01T21:38:58.7642Z","steps":["trace[1724911663] 'read index received' (duration: 279.072067ms)","trace[1724911663] 'applied index is now lower than readState.Index' (duration: 251.505µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:38:58.764331Z","caller":"traceutil/trace.go:171","msg":"trace[79341304] transaction","detail":"{read_only:false; response_revision:12978; number_of_response:1; }","duration":"389.77708ms","start":"2026-03-01T21:38:58.374521Z","end":"2026-03-01T21:38:58.764298Z","steps":["trace[79341304] 'process raft request' (duration: 389.520795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:38:58.764495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:38:58.374499Z","time spent":"389.906373ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:38:58.764599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.689459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-bjrhm\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-03-01T21:38:58.764715Z","caller":"traceutil/trace.go:171","msg":"trace[637218534] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-bjrhm; range_end:; response_count:1; response_revision:12978; }","duration":"279.841192ms","start":"2026-03-01T21:38:58.484844Z","end":"2026-03-01T21:38:58.764685Z","steps":["trace[637218534] 'agreement among raft nodes before linearized reading' (duration: 279.509545ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:59.200682Z","caller":"traceutil/trace.go:171","msg":"trace[508074229] transaction","detail":"{read_only:false; response_revision:12979; number_of_response:1; }","duration":"427.818729ms","start":"2026-03-01T21:38:58.772828Z","end":"2026-03-01T21:38:59.200647Z","steps":["trace[508074229] 'process raft request' (duration: 427.548052ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:38:59.200886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:38:58.772805Z","time spent":"427.968522ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:38:59.210277Z","caller":"traceutil/trace.go:171","msg":"trace[1592468471] linearizableReadLoop","detail":"{readStateIndex:13457; appliedIndex:13455; }","duration":"272.77495ms","start":"2026-03-01T21:38:58.93748Z","end":"2026-03-01T21:38:59.210255Z","steps":["trace[1592468471] 'read index received' (duration: 263.68825ms)","trace[1592468471] 'applied index is now lower than readState.Index' (duration: 9.08537ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:38:59.210465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.974985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mnw6m\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-01T21:38:59.210507Z","caller":"traceutil/trace.go:171","msg":"trace[566468505] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mnw6m; range_end:; response_count:1; response_revision:12982; }","duration":"273.076936ms","start":"2026-03-01T21:38:58.937419Z","end":"2026-03-01T21:38:59.210496Z","steps":["trace[566468505] 'agreement among raft nodes before linearized reading' (duration: 272.912713ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:59.210533Z","caller":"traceutil/trace.go:171","msg":"trace[1776362698] transaction","detail":"{read_only:false; response_revision:12982; number_of_response:1; }","duration":"116.372196ms","start":"2026-03-01T21:38:59.094121Z","end":"2026-03-01T21:38:59.210493Z","steps":["trace[1776362698] 'process raft request' (duration: 116.098771ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:59.210538Z","caller":"traceutil/trace.go:171","msg":"trace[1326857721] transaction","detail":"{read_only:false; response_revision:12981; number_of_response:1; }","duration":"378.332033ms","start":"2026-03-01T21:38:58.832163Z","end":"2026-03-01T21:38:59.210495Z","steps":["trace[1326857721] 'process raft request' (duration: 378.014067ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:38:59.210538Z","caller":"traceutil/trace.go:171","msg":"trace[713115584] transaction","detail":"{read_only:false; response_revision:12980; number_of_response:1; }","duration":"407.998923ms","start":"2026-03-01T21:38:58.802507Z","end":"2026-03-01T21:38:59.210506Z","steps":["trace[713115584] 'process raft request' (duration: 407.545374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:38:59.210775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:38:58.832132Z","time spent":"378.510666ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:38:59.210903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:38:58.802479Z","time spent":"408.350201ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:39:09.048099Z","caller":"traceutil/trace.go:171","msg":"trace[1949913563] transaction","detail":"{read_only:false; response_revision:13049; number_of_response:1; }","duration":"162.665229ms","start":"2026-03-01T21:39:08.885391Z","end":"2026-03-01T21:39:09.048056Z","steps":["trace[1949913563] 'process raft request' (duration: 162.330762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:39:09.452989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.330621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:39:09.453113Z","caller":"traceutil/trace.go:171","msg":"trace[275632913] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:13049; }","duration":"383.516916ms","start":"2026-03-01T21:39:09.069568Z","end":"2026-03-01T21:39:09.453084Z","steps":["trace[275632913] 'count revisions from in-memory index tree' (duration: 383.23798ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:39:09.453165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:39:09.069545Z","time spent":"383.607118ms","remote":"127.0.0.1:33564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":30,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:39:09.45351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.092003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:39:09.45361Z","caller":"traceutil/trace.go:171","msg":"trace[1789816713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13049; }","duration":"212.239236ms","start":"2026-03-01T21:39:09.241346Z","end":"2026-03-01T21:39:09.453585Z","steps":["trace[1789816713] 'range keys from in-memory index tree' (duration: 212.000542ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:39:09.455317Z","caller":"traceutil/trace.go:171","msg":"trace[1389230790] transaction","detail":"{read_only:false; response_revision:13050; number_of_response:1; }","duration":"213.758716ms","start":"2026-03-01T21:39:09.241543Z","end":"2026-03-01T21:39:09.455302Z","steps":["trace[1389230790] 'process raft request' (duration: 213.635793ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:39:09.459891Z","caller":"traceutil/trace.go:171","msg":"trace[704240055] linearizableReadLoop","detail":"{readStateIndex:13529; appliedIndex:13528; }","duration":"171.206419ms","start":"2026-03-01T21:39:09.28866Z","end":"2026-03-01T21:39:09.459866Z","steps":["trace[704240055] 'read index received' (duration: 166.876332ms)","trace[704240055] 'applied index is now lower than readState.Index' (duration: 4.328947ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:39:09.460035Z","caller":"traceutil/trace.go:171","msg":"trace[7608614] transaction","detail":"{read_only:false; response_revision:13051; number_of_response:1; }","duration":"216.219935ms","start":"2026-03-01T21:39:09.243787Z","end":"2026-03-01T21:39:09.460007Z","steps":["trace[7608614] 'process raft request' (duration: 215.99373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:39:09.460117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.447764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mnw6m\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-01T21:39:09.460163Z","caller":"traceutil/trace.go:171","msg":"trace[349641801] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mnw6m; range_end:; response_count:1; response_revision:13051; }","duration":"171.527405ms","start":"2026-03-01T21:39:09.288624Z","end":"2026-03-01T21:39:09.460152Z","steps":["trace[349641801] 'agreement among raft nodes before linearized reading' (duration: 171.357741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:39:09.460284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.634637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:39:09.460372Z","caller":"traceutil/trace.go:171","msg":"trace[1419304687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13051; }","duration":"160.791001ms","start":"2026-03-01T21:39:09.29956Z","end":"2026-03-01T21:39:09.460351Z","steps":["trace[1419304687] 'agreement among raft nodes before linearized reading' (duration: 160.608128ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:39:20.367877Z","caller":"traceutil/trace.go:171","msg":"trace[938161896] transaction","detail":"{read_only:false; response_revision:13282; number_of_response:1; }","duration":"217.364969ms","start":"2026-03-01T21:39:20.150477Z","end":"2026-03-01T21:39:20.367842Z","steps":["trace[938161896] 'process raft request' (duration: 166.435434ms)","trace[938161896] 'compare' (duration: 50.795302ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:39:20.367913Z","caller":"traceutil/trace.go:171","msg":"trace[1425008506] transaction","detail":"{read_only:false; response_revision:13283; number_of_response:1; }","duration":"216.948551ms","start":"2026-03-01T21:39:20.150942Z","end":"2026-03-01T21:39:20.367891Z","steps":["trace[1425008506] 'process raft request' (duration: 216.89984ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:39:20.367919Z","caller":"traceutil/trace.go:171","msg":"trace[981807208] linearizableReadLoop","detail":"{readStateIndex:13771; appliedIndex:13770; }","duration":"217.021132ms","start":"2026-03-01T21:39:20.150877Z","end":"2026-03-01T21:39:20.367898Z","steps":["trace[981807208] 'read index received' (duration: 166.018666ms)","trace[981807208] 'applied index is now lower than readState.Index' (duration: 51.000186ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:39:20.368111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.216186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T21:39:20.368152Z","caller":"traceutil/trace.go:171","msg":"trace[1972393910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13283; }","duration":"217.294667ms","start":"2026-03-01T21:39:20.150844Z","end":"2026-03-01T21:39:20.368139Z","steps":["trace[1972393910] 'agreement among raft nodes before linearized reading' (duration: 217.121413ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:07.347332Z","caller":"traceutil/trace.go:171","msg":"trace[207353393] linearizableReadLoop","detail":"{readStateIndex:14374; appliedIndex:14373; }","duration":"188.432022ms","start":"2026-03-01T21:40:07.158873Z","end":"2026-03-01T21:40:07.347306Z","steps":["trace[207353393] 'read index received' (duration: 188.266188ms)","trace[207353393] 'applied index is now lower than readState.Index' (duration: 164.784µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:40:07.347466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.578384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:07.347495Z","caller":"traceutil/trace.go:171","msg":"trace[220111614] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:13869; }","duration":"188.643466ms","start":"2026-03-01T21:40:07.158843Z","end":"2026-03-01T21:40:07.347487Z","steps":["trace[220111614] 'agreement among raft nodes before linearized reading' (duration: 188.583415ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:07.347467Z","caller":"traceutil/trace.go:171","msg":"trace[243926056] transaction","detail":"{read_only:false; response_revision:13869; number_of_response:1; }","duration":"197.942922ms","start":"2026-03-01T21:40:07.149485Z","end":"2026-03-01T21:40:07.347428Z","steps":["trace[243926056] 'process raft request' (duration: 197.668097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:07.396139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.179552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:07.39622Z","caller":"traceutil/trace.go:171","msg":"trace[244962508] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:13870; }","duration":"237.287433ms","start":"2026-03-01T21:40:07.158911Z","end":"2026-03-01T21:40:07.396198Z","steps":["trace[244962508] 'agreement among raft nodes before linearized reading' (duration: 237.09505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:07.39636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.832375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-03-01T21:40:07.396401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.944047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:07.39645Z","caller":"traceutil/trace.go:171","msg":"trace[1231459605] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:13870; }","duration":"230.948308ms","start":"2026-03-01T21:40:07.165485Z","end":"2026-03-01T21:40:07.396434Z","steps":["trace[1231459605] 'agreement among raft nodes before linearized reading' (duration: 230.753864ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:07.396478Z","caller":"traceutil/trace.go:171","msg":"trace[840615778] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:13870; }","duration":"237.127301ms","start":"2026-03-01T21:40:07.159331Z","end":"2026-03-01T21:40:07.396459Z","steps":["trace[840615778] 'agreement among raft nodes before linearized reading' (duration: 236.966537ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:07.396651Z","caller":"traceutil/trace.go:171","msg":"trace[2089269309] transaction","detail":"{read_only:false; response_revision:13870; number_of_response:1; }","duration":"137.532618ms","start":"2026-03-01T21:40:07.259014Z","end":"2026-03-01T21:40:07.396547Z","steps":["trace[2089269309] 'process raft request' (duration: 135.520329ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:17.873122Z","caller":"traceutil/trace.go:171","msg":"trace[2012243298] transaction","detail":"{read_only:false; response_revision:13921; number_of_response:1; }","duration":"212.741136ms","start":"2026-03-01T21:40:17.660354Z","end":"2026-03-01T21:40:17.873096Z","steps":["trace[2012243298] 'process raft request' (duration: 212.566103ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:17.901033Z","caller":"traceutil/trace.go:171","msg":"trace[1159748484] transaction","detail":"{read_only:false; response_revision:13922; number_of_response:1; }","duration":"128.316606ms","start":"2026-03-01T21:40:17.772686Z","end":"2026-03-01T21:40:17.901002Z","steps":["trace[1159748484] 'process raft request' (duration: 128.152842ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:21.946238Z","caller":"traceutil/trace.go:171","msg":"trace[269844949] linearizableReadLoop","detail":"{readStateIndex:14447; appliedIndex:14446; }","duration":"104.118463ms","start":"2026-03-01T21:40:21.842099Z","end":"2026-03-01T21:40:21.946218Z","steps":["trace[269844949] 'read index received' (duration: 104.066662ms)","trace[269844949] 'applied index is now lower than readState.Index' (duration: 50.611µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:40:21.94638Z","caller":"traceutil/trace.go:171","msg":"trace[1196356939] transaction","detail":"{read_only:false; response_revision:13939; number_of_response:1; }","duration":"154.112669ms","start":"2026-03-01T21:40:21.792235Z","end":"2026-03-01T21:40:21.946347Z","steps":["trace[1196356939] 'process raft request' (duration: 153.868484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:21.946504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.335717ms","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-01T21:40:21.946574Z","caller":"traceutil/trace.go:171","msg":"trace[1858914255] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13939; }","duration":"104.47691ms","start":"2026-03-01T21:40:21.842086Z","end":"2026-03-01T21:40:21.946563Z","steps":["trace[1858914255] 'agreement among raft nodes before linearized reading' (duration: 104.311567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:40.914684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.183802ms","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-01T21:40:40.915111Z","caller":"traceutil/trace.go:171","msg":"trace[1213393110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14027; }","duration":"128.602649ms","start":"2026-03-01T21:40:40.786397Z","end":"2026-03-01T21:40:40.915Z","steps":["trace[1213393110] 'range keys from in-memory index tree' (duration: 127.927356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:40.915326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.307466ms","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-01T21:40:40.915519Z","caller":"traceutil/trace.go:171","msg":"trace[751075422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14027; }","duration":"129.566661ms","start":"2026-03-01T21:40:40.785924Z","end":"2026-03-01T21:40:40.915491Z","steps":["trace[751075422] 'range keys from in-memory index tree' (duration: 129.163663ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:41.252193Z","caller":"traceutil/trace.go:171","msg":"trace[779279913] transaction","detail":"{read_only:false; response_revision:14028; number_of_response:1; }","duration":"331.316276ms","start":"2026-03-01T21:40:40.920847Z","end":"2026-03-01T21:40:41.252163Z","steps":["trace[779279913] 'process raft request' (duration: 331.126342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.252362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:40.920691Z","time spent":"331.587522ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:40:41.680736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.882848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T21:40:41.680905Z","caller":"traceutil/trace.go:171","msg":"trace[1840224387] linearizableReadLoop","detail":"{readStateIndex:14541; appliedIndex:14538; }","duration":"756.753407ms","start":"2026-03-01T21:40:40.924135Z","end":"2026-03-01T21:40:41.680888Z","steps":["trace[1840224387] 'read index received' (duration: 327.937259ms)","trace[1840224387] 'applied index is now lower than readState.Index' (duration: 428.814968ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:40:41.680981Z","caller":"traceutil/trace.go:171","msg":"trace[300567742] transaction","detail":"{read_only:false; response_revision:14029; number_of_response:1; }","duration":"759.37317ms","start":"2026-03-01T21:40:40.921597Z","end":"2026-03-01T21:40:41.680971Z","steps":["trace[300567742] 'process raft request' (duration: 643.147925ms)","trace[300567742] 'compare' (duration: 115.758636ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:40:41.681041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:40.921571Z","time spent":"759.439531ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T21:40:41.681103Z","caller":"traceutil/trace.go:171","msg":"trace[1872051627] transaction","detail":"{read_only:false; response_revision:14030; number_of_response:1; }","duration":"757.096514ms","start":"2026-03-01T21:40:40.92396Z","end":"2026-03-01T21:40:41.681057Z","steps":["trace[1872051627] 'process raft request' (duration: 756.8719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.681585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:40.923947Z","time spent":"757.559643ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T21:40:41.681731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.582823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-03-01T21:40:41.681857Z","caller":"traceutil/trace.go:171","msg":"trace[1936015617] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:14030; }","duration":"757.755757ms","start":"2026-03-01T21:40:40.924077Z","end":"2026-03-01T21:40:41.681833Z","steps":["trace[1936015617] 'agreement among raft nodes before linearized reading' (duration: 756.987012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.681965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:40.924068Z","time spent":"757.883179ms","remote":"127.0.0.1:40984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/162.253.55.110\" "} {"level":"info","ts":"2026-03-01T21:40:41.720847Z","caller":"traceutil/trace.go:171","msg":"trace[5452030] transaction","detail":"{read_only:false; response_revision:14031; number_of_response:1; }","duration":"217.107172ms","start":"2026-03-01T21:40:41.503718Z","end":"2026-03-01T21:40:41.720825Z","steps":["trace[5452030] 'process raft request' (duration: 216.803136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.721134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.776302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-03-01T21:40:41.721248Z","caller":"traceutil/trace.go:171","msg":"trace[2080140610] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14031; }","duration":"252.894695ms","start":"2026-03-01T21:40:41.468298Z","end":"2026-03-01T21:40:41.721193Z","steps":["trace[2080140610] 'agreement among raft nodes before linearized reading' (duration: 252.703201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.720893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.708864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sfkrr\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-03-01T21:40:41.721393Z","caller":"traceutil/trace.go:171","msg":"trace[744084010] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sfkrr; range_end:; response_count:1; response_revision:14031; }","duration":"784.231265ms","start":"2026-03-01T21:40:40.937119Z","end":"2026-03-01T21:40:41.72135Z","steps":["trace[744084010] 'agreement among raft nodes before linearized reading' (duration: 783.579902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.721472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:40.937103Z","time spent":"784.345697ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-sfkrr\" "} {"level":"warn","ts":"2026-03-01T21:40:41.720875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.245681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.721755Z","caller":"traceutil/trace.go:171","msg":"trace[265109038] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14031; }","duration":"265.172489ms","start":"2026-03-01T21:40:41.456568Z","end":"2026-03-01T21:40:41.72174Z","steps":["trace[265109038] 'agreement among raft nodes before linearized reading' (duration: 264.1954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.720915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.362305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-03-01T21:40:41.721964Z","caller":"traceutil/trace.go:171","msg":"trace[1628758138] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14031; }","duration":"251.459007ms","start":"2026-03-01T21:40:41.470492Z","end":"2026-03-01T21:40:41.721951Z","steps":["trace[1628758138] 'agreement among raft nodes before linearized reading' (duration: 250.288154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.720951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.884053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.722179Z","caller":"traceutil/trace.go:171","msg":"trace[879634669] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14031; }","duration":"287.149489ms","start":"2026-03-01T21:40:41.435008Z","end":"2026-03-01T21:40:41.722158Z","steps":["trace[879634669] 'agreement among raft nodes before linearized reading' (duration: 285.925944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.720978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.858659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.722319Z","caller":"traceutil/trace.go:171","msg":"trace[427644785] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14031; }","duration":"540.142705ms","start":"2026-03-01T21:40:41.182099Z","end":"2026-03-01T21:40:41.722242Z","steps":["trace[427644785] 'agreement among raft nodes before linearized reading' (duration: 538.859409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.722378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:41.182094Z","time spent":"540.268007ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-03-01T21:40:41.720943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.338054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.722463Z","caller":"traceutil/trace.go:171","msg":"trace[124548497] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14031; }","duration":"251.890965ms","start":"2026-03-01T21:40:41.470559Z","end":"2026-03-01T21:40:41.72245Z","steps":["trace[124548497] 'agreement among raft nodes before linearized reading' (duration: 250.300923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.721009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.844902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sfkrr\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-03-01T21:40:41.722559Z","caller":"traceutil/trace.go:171","msg":"trace[235696647] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sfkrr; range_end:; response_count:1; response_revision:14031; }","duration":"732.438224ms","start":"2026-03-01T21:40:40.99011Z","end":"2026-03-01T21:40:41.722548Z","steps":["trace[235696647] 'agreement among raft nodes before linearized reading' (duration: 730.858042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.722598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:40.990088Z","time spent":"732.499835ms","remote":"127.0.0.1:38506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22123,"request content":"key:\"/registry/pods/openstack/nova-compute-default-sfkrr\" "} {"level":"warn","ts":"2026-03-01T21:40:41.721016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.087274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.72271Z","caller":"traceutil/trace.go:171","msg":"trace[1894673466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14031; }","duration":"422.819359ms","start":"2026-03-01T21:40:41.299872Z","end":"2026-03-01T21:40:41.722691Z","steps":["trace[1894673466] 'agreement among raft nodes before linearized reading' (duration: 421.124225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.721046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.640995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.722844Z","caller":"traceutil/trace.go:171","msg":"trace[1329632067] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14031; }","duration":"540.445321ms","start":"2026-03-01T21:40:41.182387Z","end":"2026-03-01T21:40:41.722833Z","steps":["trace[1329632067] 'agreement among raft nodes before linearized reading' (duration: 538.643714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.722815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:41.29984Z","time spent":"422.960322ms","remote":"127.0.0.1:40956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-01T21:40:41.721071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.367035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-03-01T21:40:41.722982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:41.182383Z","time spent":"540.492092ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"info","ts":"2026-03-01T21:40:41.722981Z","caller":"traceutil/trace.go:171","msg":"trace[230952581] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14031; }","duration":"253.282083ms","start":"2026-03-01T21:40:41.469684Z","end":"2026-03-01T21:40:41.722966Z","steps":["trace[230952581] 'agreement among raft nodes before linearized reading' (duration: 251.343314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.721113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.746957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-03-01T21:40:41.723647Z","caller":"traceutil/trace.go:171","msg":"trace[1685579753] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14031; }","duration":"541.286447ms","start":"2026-03-01T21:40:41.182345Z","end":"2026-03-01T21:40:41.723631Z","steps":["trace[1685579753] 'agreement among raft nodes before linearized reading' (duration: 538.717106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.72375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:41.182338Z","time spent":"541.391389ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5544,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-03-01T21:40:41.721108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.257136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T21:40:41.724061Z","caller":"traceutil/trace.go:171","msg":"trace[1233124308] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:14031; }","duration":"560.248855ms","start":"2026-03-01T21:40:41.1638Z","end":"2026-03-01T21:40:41.724049Z","steps":["trace[1233124308] 'agreement among raft nodes before linearized reading' (duration: 557.166014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.724152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:41.163778Z","time spent":"560.359207ms","remote":"127.0.0.1:41076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":120,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-01T21:40:41.72113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.371833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.72452Z","caller":"traceutil/trace.go:171","msg":"trace[1431976306] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14031; }","duration":"267.798331ms","start":"2026-03-01T21:40:41.456704Z","end":"2026-03-01T21:40:41.724503Z","steps":["trace[1431976306] 'agreement among raft nodes before linearized reading' (duration: 264.415104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.721093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.871444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-03-01T21:40:41.72466Z","caller":"traceutil/trace.go:171","msg":"trace[670838188] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14031; }","duration":"254.459635ms","start":"2026-03-01T21:40:41.470188Z","end":"2026-03-01T21:40:41.724648Z","steps":["trace[670838188] 'agreement among raft nodes before linearized reading' (duration: 250.751192ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.721104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.026613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T21:40:41.724758Z","caller":"traceutil/trace.go:171","msg":"trace[1656777901] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14031; }","duration":"542.710326ms","start":"2026-03-01T21:40:41.182036Z","end":"2026-03-01T21:40:41.724747Z","steps":["trace[1656777901] 'agreement among raft nodes before linearized reading' (duration: 539.055493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T21:40:41.724804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T21:40:41.18202Z","time spent":"542.771008ms","remote":"127.0.0.1:38556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-03-01T21:40:42.250475Z","caller":"traceutil/trace.go:171","msg":"trace[1036633578] linearizableReadLoop","detail":"{readStateIndex:14546; appliedIndex:14544; }","duration":"170.25958ms","start":"2026-03-01T21:40:42.08019Z","end":"2026-03-01T21:40:42.25045Z","steps":["trace[1036633578] 'read index received' (duration: 30.297543ms)","trace[1036633578] 'applied index is now lower than readState.Index' (duration: 139.961057ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T21:40:42.250689Z","caller":"traceutil/trace.go:171","msg":"trace[1950194877] transaction","detail":"{read_only:false; response_revision:14034; number_of_response:1; }","duration":"173.836622ms","start":"2026-03-01T21:40:42.076821Z","end":"2026-03-01T21:40:42.250657Z","steps":["trace[1950194877] 'process raft request' (duration: 147.303813ms)","trace[1950194877] 'compare' (duration: 26.226343ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T21:40:42.250798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.530782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T21:40:42.25081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.606287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T21:40:42.25086Z","caller":"traceutil/trace.go:171","msg":"trace[501502674] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:14034; }","duration":"100.652844ms","start":"2026-03-01T21:40:42.150192Z","end":"2026-03-01T21:40:42.250845Z","steps":["trace[501502674] 'agreement among raft nodes before linearized reading' (duration: 100.528172ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:40:42.250869Z","caller":"traceutil/trace.go:171","msg":"trace[112201184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14034; }","duration":"170.725549ms","start":"2026-03-01T21:40:42.08013Z","end":"2026-03-01T21:40:42.250856Z","steps":["trace[112201184] 'agreement among raft nodes before linearized reading' (duration: 170.506875ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T21:41:27.745914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11971} {"level":"info","ts":"2026-03-01T21:41:27.818887Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11971,"took":"70.19985ms","hash":1926122069,"current-db-size-bytes":41881600,"current-db-size":"42 MB","current-db-size-in-use-bytes":26873856,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-01T21:41:27.819028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1926122069,"revision":11971,"compact-revision":10022} {"level":"info","ts":"2026-03-01T21:46:27.753257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14309} {"level":"info","ts":"2026-03-01T21:46:27.849139Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14309,"took":"92.851622ms","hash":1539528151,"current-db-size-bytes":42303488,"current-db-size":"42 MB","current-db-size-in-use-bytes":28344320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-01T21:46:27.849289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1539528151,"revision":14309,"compact-revision":11971} {"level":"info","ts":"2026-03-01T21:51:27.759816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16392} {"level":"info","ts":"2026-03-01T21:51:27.855625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16392,"took":"92.608478ms","hash":2828541016,"current-db-size-bytes":42303488,"current-db-size":"42 MB","current-db-size-in-use-bytes":21880832,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-01T21:51:27.855708Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2828541016,"revision":16392,"compact-revision":14309}