{"level":"warn","ts":"2026-07-15T04:55:39.581231Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:55:39.581319Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-07-15T04:55:39.581383Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T04:55:39.58139Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-15T04:55:39.581406Z","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-07-15T04:55:39.582248Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-15T04:55:39.582347Z","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://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-07-15T04:55:39.585605Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.919442ms"} {"level":"info","ts":"2026-07-15T04:55:39.591184Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-15T04:55:39.591259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T04:55:39.591288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-15T04:55:39.591308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T04:55:39.591324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-15T04:55:39.591362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-15T04:55:39.596453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T04:55:39.598017Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T04:55:39.599299Z","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-07-15T04:55:39.602921Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T04:55:39.603263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T04:55:39.603322Z","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-07-15T04:55:39.603422Z","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-07-15T04:55:39.603442Z","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-07-15T04:55:39.605289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-15T04:55:39.605451Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-15T04:55:39.607464Z","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-07-15T04:55:39.607594Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-15T04:55:39.607665Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-15T04:55:39.607701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T04:55:39.607748Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T04:55:39.792238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T04:55:39.792303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T04:55:39.792328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-15T04:55:39.792367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-15T04:55:39.792411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-15T04:55:39.792427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-15T04:55:39.792448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-15T04:55:39.794055Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:55:39.794979Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T04:55:39.794982Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:55:39.795035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T04:55:39.795442Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T04:55:39.795447Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:55:39.795469Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T04:55:39.795569Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:55:39.795615Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T04:55:39.798867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T04:55:39.798875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-15T04:56:21.398346Z","caller":"traceutil/trace.go:171","msg":"trace[2013754333] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"100.559703ms","start":"2026-07-15T04:56:21.297758Z","end":"2026-07-15T04:56:21.398318Z","steps":["trace[2013754333] 'read index received' (duration: 98.886843ms)","trace[2013754333] 'applied index is now lower than readState.Index' (duration: 1.67201ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:56:21.399385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.59908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T04:56:21.399633Z","caller":"traceutil/trace.go:171","msg":"trace[1510105531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"101.878787ms","start":"2026-07-15T04:56:21.297706Z","end":"2026-07-15T04:56:21.399585Z","steps":["trace[1510105531] 'agreement among raft nodes before linearized reading' (duration: 100.724198ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:56:21.398399Z","caller":"traceutil/trace.go:171","msg":"trace[297371510] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"122.829796ms","start":"2026-07-15T04:56:21.275544Z","end":"2026-07-15T04:56:21.398373Z","steps":["trace[297371510] 'process raft request' (duration: 121.767911ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:56:21.560695Z","caller":"traceutil/trace.go:171","msg":"trace[550947426] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"129.367946ms","start":"2026-07-15T04:56:21.431298Z","end":"2026-07-15T04:56:21.560666Z","steps":["trace[550947426] 'read index received' (duration: 42.289552ms)","trace[550947426] 'applied index is now lower than readState.Index' (duration: 87.077144ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:56:21.560855Z","caller":"traceutil/trace.go:171","msg":"trace[1072300701] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"155.246987ms","start":"2026-07-15T04:56:21.405546Z","end":"2026-07-15T04:56:21.560793Z","steps":["trace[1072300701] 'process raft request' (duration: 67.990158ms)","trace[1072300701] 'compare' (duration: 87.006463ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:56:21.560886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.56535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:56:21.560989Z","caller":"traceutil/trace.go:171","msg":"trace[1315192382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"129.695994ms","start":"2026-07-15T04:56:21.431269Z","end":"2026-07-15T04:56:21.560965Z","steps":["trace[1315192382] 'agreement among raft nodes before linearized reading' (duration: 129.504219ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:56:21.660126Z","caller":"traceutil/trace.go:171","msg":"trace[694990047] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"203.519636ms","start":"2026-07-15T04:56:21.456584Z","end":"2026-07-15T04:56:21.660104Z","steps":["trace[694990047] 'process raft request' (duration: 203.420963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:56:22.293842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.159611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:56:22.294107Z","caller":"traceutil/trace.go:171","msg":"trace[469589787] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"475.175974ms","start":"2026-07-15T04:56:21.818873Z","end":"2026-07-15T04:56:22.294049Z","steps":["trace[469589787] 'process raft request' (duration: 270.07818ms)","trace[469589787] 'compare' (duration: 204.009078ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:56:22.294209Z","caller":"traceutil/trace.go:171","msg":"trace[790680158] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"358.883775ms","start":"2026-07-15T04:56:21.935281Z","end":"2026-07-15T04:56:22.294165Z","steps":["trace[790680158] 'read index received' (duration: 153.68626ms)","trace[790680158] 'applied index is now lower than readState.Index' (duration: 205.192125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:56:22.294243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:56:21.818844Z","time spent":"475.352087ms","remote":"127.0.0.1:39084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:56:22.294529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.221623ms","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-07-15T04:56:22.294571Z","caller":"traceutil/trace.go:171","msg":"trace[1093250316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"359.311356ms","start":"2026-07-15T04:56:21.935249Z","end":"2026-07-15T04:56:22.29456Z","steps":["trace[1093250316] 'agreement among raft nodes before linearized reading' (duration: 359.095681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:56:22.294608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:56:21.935231Z","time spent":"359.369127ms","remote":"127.0.0.1:39158","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-07-15T04:56:22.294676Z","caller":"traceutil/trace.go:171","msg":"trace[512921440] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"216.352558ms","start":"2026-07-15T04:56:22.078286Z","end":"2026-07-15T04:56:22.294638Z","steps":["trace[512921440] 'process raft request' (duration: 215.760384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:56:22.295183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.775748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T04:56:22.295239Z","caller":"traceutil/trace.go:171","msg":"trace[202436768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"257.856541ms","start":"2026-07-15T04:56:22.03737Z","end":"2026-07-15T04:56:22.295226Z","steps":["trace[202436768] 'agreement among raft nodes before linearized reading' (duration: 257.559704ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:56:22.295256Z","caller":"traceutil/trace.go:171","msg":"trace[1751245050] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"141.00817ms","start":"2026-07-15T04:56:22.154226Z","end":"2026-07-15T04:56:22.295234Z","steps":["trace[1751245050] 'process raft request' (duration: 140.135209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:57:16.875324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.60579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T04:57:16.875535Z","caller":"traceutil/trace.go:171","msg":"trace[23293506] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"181.539219ms","start":"2026-07-15T04:57:16.693968Z","end":"2026-07-15T04:57:16.875507Z","steps":["trace[23293506] 'process raft request' (duration: 35.614091ms)","trace[23293506] 'compare' (duration: 145.216781ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T04:58:03.10663Z","caller":"traceutil/trace.go:171","msg":"trace[1296548140] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"244.191161ms","start":"2026-07-15T04:58:02.862413Z","end":"2026-07-15T04:58:03.106604Z","steps":["trace[1296548140] 'process raft request' (duration: 243.957305ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:58:03.199874Z","caller":"traceutil/trace.go:171","msg":"trace[229709843] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"337.355121ms","start":"2026-07-15T04:58:02.862488Z","end":"2026-07-15T04:58:03.199843Z","steps":["trace[229709843] 'process raft request' (duration: 323.270867ms)","trace[229709843] 'compare' (duration: 13.834408ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:03.199989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:02.862463Z","time spent":"337.468184ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:58:03.200227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.36727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:58:03.200585Z","caller":"traceutil/trace.go:171","msg":"trace[1167127012] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1859; }","duration":"316.745939ms","start":"2026-07-15T04:58:02.883816Z","end":"2026-07-15T04:58:03.200562Z","steps":["trace[1167127012] 'agreement among raft nodes before linearized reading' (duration: 316.330379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:03.200694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:02.883798Z","time spent":"316.878092ms","remote":"127.0.0.1:58426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-07-15T04:58:03.200274Z","caller":"traceutil/trace.go:171","msg":"trace[1944545526] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1932; }","duration":"315.94272ms","start":"2026-07-15T04:58:02.883832Z","end":"2026-07-15T04:58:03.199775Z","steps":["trace[1944545526] 'read index received' (duration: 222.568055ms)","trace[1944545526] 'applied index is now lower than readState.Index' (duration: 93.373565ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:03.201022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.078197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T04:58:03.201084Z","caller":"traceutil/trace.go:171","msg":"trace[180089488] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1859; }","duration":"262.174599ms","start":"2026-07-15T04:58:02.938893Z","end":"2026-07-15T04:58:03.201068Z","steps":["trace[180089488] 'agreement among raft nodes before linearized reading' (duration: 262.076577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:03.201162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.375663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f35c37d-4c08-47b9-9ca1-3d2e1333c694\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-15T04:58:03.201241Z","caller":"traceutil/trace.go:171","msg":"trace[1126620822] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0f35c37d-4c08-47b9-9ca1-3d2e1333c694; range_end:; response_count:1; response_revision:1859; }","duration":"239.498237ms","start":"2026-07-15T04:58:02.961722Z","end":"2026-07-15T04:58:03.201221Z","steps":["trace[1126620822] 'agreement among raft nodes before linearized reading' (duration: 239.272461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:03.201619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.796556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-15T04:58:03.201674Z","caller":"traceutil/trace.go:171","msg":"trace[2135544044] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1859; }","duration":"180.870878ms","start":"2026-07-15T04:58:03.02079Z","end":"2026-07-15T04:58:03.201661Z","steps":["trace[2135544044] 'agreement among raft nodes before linearized reading' (duration: 180.176592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:03.582931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.357215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:58:03.583032Z","caller":"traceutil/trace.go:171","msg":"trace[2005251150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1860; }","duration":"148.489108ms","start":"2026-07-15T04:58:03.434523Z","end":"2026-07-15T04:58:03.583013Z","steps":["trace[2005251150] 'range keys from in-memory index tree' (duration: 148.298974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:03.583291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.207746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0f35c37d-4c08-47b9-9ca1-3d2e1333c694\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-15T04:58:03.583371Z","caller":"traceutil/trace.go:171","msg":"trace[2006306110] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0f35c37d-4c08-47b9-9ca1-3d2e1333c694; range_end:; response_count:1; response_revision:1860; }","duration":"275.320708ms","start":"2026-07-15T04:58:03.308033Z","end":"2026-07-15T04:58:03.583353Z","steps":["trace[2006306110] 'range keys from in-memory index tree' (duration: 275.016831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:03.583358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.491139ms","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-07-15T04:58:03.583459Z","caller":"traceutil/trace.go:171","msg":"trace[1929690367] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"171.635012ms","start":"2026-07-15T04:58:03.411802Z","end":"2026-07-15T04:58:03.583437Z","steps":["trace[1929690367] 'range keys from in-memory index tree' (duration: 171.319554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:18.697321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.757481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T04:58:18.697381Z","caller":"traceutil/trace.go:171","msg":"trace[282800440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1990; }","duration":"264.857184ms","start":"2026-07-15T04:58:18.432511Z","end":"2026-07-15T04:58:18.697369Z","steps":["trace[282800440] 'range keys from in-memory index tree' (duration: 264.67678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:18.697398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.975292ms","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-07-15T04:58:18.697506Z","caller":"traceutil/trace.go:171","msg":"trace[470061628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1990; }","duration":"296.133536ms","start":"2026-07-15T04:58:18.401348Z","end":"2026-07-15T04:58:18.697482Z","steps":["trace[470061628] 'range keys from in-memory index tree' (duration: 295.777068ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:58:18.697497Z","caller":"traceutil/trace.go:171","msg":"trace[502634652] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"249.296425ms","start":"2026-07-15T04:58:18.448169Z","end":"2026-07-15T04:58:18.697466Z","steps":["trace[502634652] 'process raft request' (duration: 226.852178ms)","trace[502634652] 'compare' (duration: 22.134809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:58.717649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.50693ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-15T04:58:58.718025Z","caller":"traceutil/trace.go:171","msg":"trace[1350001218] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"340.213741ms","start":"2026-07-15T04:58:58.377782Z","end":"2026-07-15T04:58:58.717996Z","steps":["trace[1350001218] 'process raft request' (duration: 339.959175ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:58:58.718047Z","caller":"traceutil/trace.go:171","msg":"trace[921574891] linearizableReadLoop","detail":"{readStateIndex:2296; appliedIndex:2294; }","duration":"365.585689ms","start":"2026-07-15T04:58:58.352436Z","end":"2026-07-15T04:58:58.718021Z","steps":["trace[921574891] 'read index received' (duration: 34.240743ms)","trace[921574891] 'applied index is now lower than readState.Index' (duration: 331.343786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:58.718174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:58.377758Z","time spent":"340.342575ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T04:58:58.732461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.029091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T04:58:58.732543Z","caller":"traceutil/trace.go:171","msg":"trace[41859435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2208; }","duration":"380.151184ms","start":"2026-07-15T04:58:58.352368Z","end":"2026-07-15T04:58:58.732519Z","steps":["trace[41859435] 'agreement among raft nodes before linearized reading' (duration: 365.741083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:58.732589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:58.352343Z","time spent":"380.235015ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T04:58:58.732801Z","caller":"traceutil/trace.go:171","msg":"trace[1740650281] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"234.299094ms","start":"2026-07-15T04:58:58.498439Z","end":"2026-07-15T04:58:58.732738Z","steps":["trace[1740650281] 'process raft request' (duration: 219.574065ms)","trace[1740650281] 'compare' (duration: 14.432351ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T04:58:58.73286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.634951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T04:58:58.732873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.323861ms","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-07-15T04:58:58.732919Z","caller":"traceutil/trace.go:171","msg":"trace[632894851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2209; }","duration":"298.724593ms","start":"2026-07-15T04:58:58.434179Z","end":"2026-07-15T04:58:58.732903Z","steps":["trace[632894851] 'agreement among raft nodes before linearized reading' (duration: 298.642021ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T04:58:58.732959Z","caller":"traceutil/trace.go:171","msg":"trace[1846606276] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2209; }","duration":"369.431034ms","start":"2026-07-15T04:58:58.363503Z","end":"2026-07-15T04:58:58.732934Z","steps":["trace[1846606276] 'agreement among raft nodes before linearized reading' (duration: 369.095776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T04:58:58.733139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T04:58:58.363493Z","time spent":"369.628598ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-15T05:00:08.006581Z","caller":"traceutil/trace.go:171","msg":"trace[1799097666] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"142.511131ms","start":"2026-07-15T05:00:07.864044Z","end":"2026-07-15T05:00:08.006555Z","steps":["trace[1799097666] 'process raft request' (duration: 142.307447ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:02:38.020582Z","caller":"traceutil/trace.go:171","msg":"trace[1812078483] linearizableReadLoop","detail":"{readStateIndex:3919; appliedIndex:3918; }","duration":"144.887181ms","start":"2026-07-15T05:02:37.875671Z","end":"2026-07-15T05:02:38.020558Z","steps":["trace[1812078483] 'read index received' (duration: 144.729657ms)","trace[1812078483] 'applied index is now lower than readState.Index' (duration: 156.374µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:02:38.020695Z","caller":"traceutil/trace.go:171","msg":"trace[900610917] transaction","detail":"{read_only:false; response_revision:3773; number_of_response:1; }","duration":"145.160417ms","start":"2026-07-15T05:02:37.875496Z","end":"2026-07-15T05:02:38.020656Z","steps":["trace[900610917] 'process raft request' (duration: 144.85542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:02:38.02072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.035494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T05:02:38.020765Z","caller":"traceutil/trace.go:171","msg":"trace[57445148] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:3773; }","duration":"145.143957ms","start":"2026-07-15T05:02:37.875613Z","end":"2026-07-15T05:02:38.020757Z","steps":["trace[57445148] 'agreement among raft nodes before linearized reading' (duration: 145.048924ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:02:38.02247Z","caller":"traceutil/trace.go:171","msg":"trace[1787193205] transaction","detail":"{read_only:false; response_revision:3774; number_of_response:1; }","duration":"146.200392ms","start":"2026-07-15T05:02:37.87625Z","end":"2026-07-15T05:02:38.022451Z","steps":["trace[1787193205] 'process raft request' (duration: 146.11963ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:59.763964Z","caller":"traceutil/trace.go:171","msg":"trace[1849530230] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"134.45512ms","start":"2026-07-15T05:03:59.629475Z","end":"2026-07-15T05:03:59.76393Z","steps":["trace[1849530230] 'process raft request' (duration: 134.362618ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:03:59.763985Z","caller":"traceutil/trace.go:171","msg":"trace[1684695588] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"134.865479ms","start":"2026-07-15T05:03:59.629087Z","end":"2026-07-15T05:03:59.763953Z","steps":["trace[1684695588] 'process raft request' (duration: 126.560207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:02.190925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.725489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:04:02.191206Z","caller":"traceutil/trace.go:171","msg":"trace[1462672912] linearizableReadLoop","detail":"{readStateIndex:5126; appliedIndex:5124; }","duration":"166.099079ms","start":"2026-07-15T05:04:02.025086Z","end":"2026-07-15T05:04:02.191185Z","steps":["trace[1462672912] 'read index received' (duration: 12.017952ms)","trace[1462672912] 'applied index is now lower than readState.Index' (duration: 154.079957ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:04:02.191208Z","caller":"traceutil/trace.go:171","msg":"trace[114128131] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"208.561481ms","start":"2026-07-15T05:04:01.982597Z","end":"2026-07-15T05:04:02.191159Z","steps":["trace[114128131] 'process raft request' (duration: 54.485485ms)","trace[114128131] 'compare' (duration: 153.233346ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:04:02.191341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.268743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:04:02.191321Z","caller":"traceutil/trace.go:171","msg":"trace[1492085688] transaction","detail":"{read_only:false; response_revision:4937; number_of_response:1; }","duration":"196.349424ms","start":"2026-07-15T05:04:01.994938Z","end":"2026-07-15T05:04:02.191287Z","steps":["trace[1492085688] 'process raft request' (duration: 196.118209ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:02.191388Z","caller":"traceutil/trace.go:171","msg":"trace[809957215] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4937; }","duration":"166.361616ms","start":"2026-07-15T05:04:02.025015Z","end":"2026-07-15T05:04:02.191376Z","steps":["trace[809957215] 'agreement among raft nodes before linearized reading' (duration: 166.286434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:02.191765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.289218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7242f66c-47a2-423c-abf3-9e2205032a65\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-15T05:04:02.191916Z","caller":"traceutil/trace.go:171","msg":"trace[759669818] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7242f66c-47a2-423c-abf3-9e2205032a65; range_end:; response_count:1; response_revision:4937; }","duration":"130.492654ms","start":"2026-07-15T05:04:02.0614Z","end":"2026-07-15T05:04:02.191893Z","steps":["trace[759669818] 'agreement among raft nodes before linearized reading' (duration: 130.034312ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:04.31228Z","caller":"traceutil/trace.go:171","msg":"trace[106034130] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"114.182726ms","start":"2026-07-15T05:04:04.19807Z","end":"2026-07-15T05:04:04.312252Z","steps":["trace[106034130] 'process raft request' (duration: 94.169689ms)","trace[106034130] 'compare' (duration: 19.635008ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:04:24.501767Z","caller":"traceutil/trace.go:171","msg":"trace[1010752084] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"122.832722ms","start":"2026-07-15T05:04:24.378913Z","end":"2026-07-15T05:04:24.501745Z","steps":["trace[1010752084] 'process raft request' (duration: 122.77913ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:24.501853Z","caller":"traceutil/trace.go:171","msg":"trace[984982636] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"124.174835ms","start":"2026-07-15T05:04:24.377655Z","end":"2026-07-15T05:04:24.50183Z","steps":["trace[984982636] 'process raft request' (duration: 123.97996ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:04:24.502056Z","caller":"traceutil/trace.go:171","msg":"trace[216311532] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"124.951105ms","start":"2026-07-15T05:04:24.377082Z","end":"2026-07-15T05:04:24.502033Z","steps":["trace[216311532] 'process raft request' (duration: 93.543209ms)","trace[216311532] 'compare' (duration: 30.901962ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:04:24.53676Z","caller":"traceutil/trace.go:171","msg":"trace[914806612] linearizableReadLoop","detail":"{readStateIndex:5610; appliedIndex:5606; }","duration":"138.086994ms","start":"2026-07-15T05:04:24.398651Z","end":"2026-07-15T05:04:24.536738Z","steps":["trace[914806612] 'read index received' (duration: 72.045184ms)","trace[914806612] 'applied index is now lower than readState.Index' (duration: 66.04056ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:04:24.53683Z","caller":"traceutil/trace.go:171","msg":"trace[677910874] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"145.348591ms","start":"2026-07-15T05:04:24.391445Z","end":"2026-07-15T05:04:24.536794Z","steps":["trace[677910874] 'process raft request' (duration: 145.166087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:04:24.537016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.33719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-15T05:04:24.5371Z","caller":"traceutil/trace.go:171","msg":"trace[343701717] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5408; }","duration":"138.481344ms","start":"2026-07-15T05:04:24.398598Z","end":"2026-07-15T05:04:24.537079Z","steps":["trace[343701717] 'agreement among raft nodes before linearized reading' (duration: 138.226778ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:05:40.161752Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-07-15T05:05:40.230541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"67.719569ms","hash":1283208794,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":34230272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T05:05:40.2307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1283208794,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-07-15T05:10:40.122435Z","caller":"traceutil/trace.go:171","msg":"trace[1917946439] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"259.237579ms","start":"2026-07-15T05:10:39.863167Z","end":"2026-07-15T05:10:40.122404Z","steps":["trace[1917946439] 'process raft request' (duration: 259.007394ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:10:40.317499Z","caller":"traceutil/trace.go:171","msg":"trace[1678924783] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"324.656683ms","start":"2026-07-15T05:10:39.992808Z","end":"2026-07-15T05:10:40.317465Z","steps":["trace[1678924783] 'process raft request' (duration: 324.433627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:10:40.317721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:10:39.992777Z","time spent":"324.831407ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:10:40.318496Z","caller":"traceutil/trace.go:171","msg":"trace[457721740] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"145.163178ms","start":"2026-07-15T05:10:40.173305Z","end":"2026-07-15T05:10:40.318468Z","steps":["trace[457721740] 'process raft request' (duration: 145.033985ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:10:40.322267Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6060} {"level":"info","ts":"2026-07-15T05:10:40.42085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6060,"took":"96.247946ms","hash":1642508490,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":23998464,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-15T05:10:40.420932Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1642508490,"revision":6060,"compact-revision":2960} {"level":"info","ts":"2026-07-15T05:10:53.041205Z","caller":"traceutil/trace.go:171","msg":"trace[1218604504] transaction","detail":"{read_only:false; response_revision:8321; number_of_response:1; }","duration":"282.512797ms","start":"2026-07-15T05:10:52.758642Z","end":"2026-07-15T05:10:53.041154Z","steps":["trace[1218604504] 'process raft request' (duration: 211.863655ms)","trace[1218604504] 'compare' (duration: 70.401196ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:10:53.041365Z","caller":"traceutil/trace.go:171","msg":"trace[2006506955] transaction","detail":"{read_only:false; response_revision:8322; number_of_response:1; }","duration":"282.252459ms","start":"2026-07-15T05:10:52.759078Z","end":"2026-07-15T05:10:53.04133Z","steps":["trace[2006506955] 'process raft request' (duration: 282.019894ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:21.655676Z","caller":"traceutil/trace.go:171","msg":"trace[473977822] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"445.249053ms","start":"2026-07-15T05:11:21.210395Z","end":"2026-07-15T05:11:21.655644Z","steps":["trace[473977822] 'process raft request' (duration: 445.024888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:21.656129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:11:21.210378Z","time spent":"445.636053ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:11:21.668796Z","caller":"traceutil/trace.go:171","msg":"trace[1350757638] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"458.309451ms","start":"2026-07-15T05:11:21.210456Z","end":"2026-07-15T05:11:21.668765Z","steps":["trace[1350757638] 'process raft request' (duration: 458.128537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:21.668978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:11:21.210431Z","time spent":"458.450564ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:11:21.669158Z","caller":"traceutil/trace.go:171","msg":"trace[957821856] linearizableReadLoop","detail":"{readStateIndex:8832; appliedIndex:8830; }","duration":"429.942441ms","start":"2026-07-15T05:11:21.239183Z","end":"2026-07-15T05:11:21.669125Z","steps":["trace[957821856] 'read index received' (duration: 416.274938ms)","trace[957821856] 'applied index is now lower than readState.Index' (duration: 13.666303ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:11:21.669199Z","caller":"traceutil/trace.go:171","msg":"trace[787079708] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"374.359826ms","start":"2026-07-15T05:11:21.294803Z","end":"2026-07-15T05:11:21.669163Z","steps":["trace[787079708] 'process raft request' (duration: 373.919364ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:21.669262Z","caller":"traceutil/trace.go:171","msg":"trace[718420668] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"265.776439ms","start":"2026-07-15T05:11:21.403427Z","end":"2026-07-15T05:11:21.669203Z","steps":["trace[718420668] 'process raft request' (duration: 265.544382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:21.669374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:11:21.294783Z","time spent":"374.492889ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:11:21.671255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.618578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T05:11:21.671407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.418943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-15T05:11:21.67143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.216656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hgrht\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-07-15T05:11:21.671459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.674357ms","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-07-15T05:11:21.671469Z","caller":"traceutil/trace.go:171","msg":"trace[806876389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8530; }","duration":"261.517274ms","start":"2026-07-15T05:11:21.409938Z","end":"2026-07-15T05:11:21.671455Z","steps":["trace[806876389] 'agreement among raft nodes before linearized reading' (duration: 259.396513ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:21.671489Z","caller":"traceutil/trace.go:171","msg":"trace[1467584873] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hgrht; range_end:; response_count:1; response_revision:8530; }","duration":"432.337099ms","start":"2026-07-15T05:11:21.239138Z","end":"2026-07-15T05:11:21.671475Z","steps":["trace[1467584873] 'agreement among raft nodes before linearized reading' (duration: 430.170017ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:21.671514Z","caller":"traceutil/trace.go:171","msg":"trace[1888001710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8530; }","duration":"231.741738ms","start":"2026-07-15T05:11:21.439759Z","end":"2026-07-15T05:11:21.671501Z","steps":["trace[1888001710] 'agreement among raft nodes before linearized reading' (duration: 229.598867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:21.671532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:11:21.239121Z","time spent":"432.40164ms","remote":"127.0.0.1:39084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-hgrht\" "} {"level":"info","ts":"2026-07-15T05:11:21.671591Z","caller":"traceutil/trace.go:171","msg":"trace[894929437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8530; }","duration":"236.846093ms","start":"2026-07-15T05:11:21.434593Z","end":"2026-07-15T05:11:21.671439Z","steps":["trace[894929437] 'agreement among raft nodes before linearized reading' (duration: 234.774663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:25.982028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.488461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:11:25.982173Z","caller":"traceutil/trace.go:171","msg":"trace[1563042911] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"206.812632ms","start":"2026-07-15T05:11:25.775346Z","end":"2026-07-15T05:11:25.982158Z","steps":["trace[1563042911] 'process raft request' (duration: 206.775751ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:25.982301Z","caller":"traceutil/trace.go:171","msg":"trace[514399789] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"297.39556ms","start":"2026-07-15T05:11:25.684899Z","end":"2026-07-15T05:11:25.982295Z","steps":["trace[514399789] 'process raft request' (duration: 166.375066ms)","trace[514399789] 'compare' (duration: 130.325797ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:11:25.982366Z","caller":"traceutil/trace.go:171","msg":"trace[1147697198] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8852; }","duration":"296.526628ms","start":"2026-07-15T05:11:25.685833Z","end":"2026-07-15T05:11:25.98236Z","steps":["trace[1147697198] 'read index received' (duration: 31.086808ms)","trace[1147697198] 'applied index is now lower than readState.Index' (duration: 265.43898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:11:25.98269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.938136ms","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-07-15T05:11:25.982716Z","caller":"traceutil/trace.go:171","msg":"trace[1051542973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8550; }","duration":"291.986298ms","start":"2026-07-15T05:11:25.690719Z","end":"2026-07-15T05:11:25.982705Z","steps":["trace[1051542973] 'agreement among raft nodes before linearized reading' (duration: 291.886765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:25.982715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.465002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-15T05:11:25.98275Z","caller":"traceutil/trace.go:171","msg":"trace[536174838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8550; }","duration":"288.517533ms","start":"2026-07-15T05:11:25.694222Z","end":"2026-07-15T05:11:25.98274Z","steps":["trace[536174838] 'agreement among raft nodes before linearized reading' (duration: 288.3923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:25.982753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.451785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hgrht\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-07-15T05:11:25.982724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.853366ms","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-07-15T05:11:25.982801Z","caller":"traceutil/trace.go:171","msg":"trace[190564871] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hgrht; range_end:; response_count:1; response_revision:8550; }","duration":"289.544558ms","start":"2026-07-15T05:11:25.693241Z","end":"2026-07-15T05:11:25.982786Z","steps":["trace[190564871] 'agreement among raft nodes before linearized reading' (duration: 289.408024ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:25.982859Z","caller":"traceutil/trace.go:171","msg":"trace[67081951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8550; }","duration":"297.158544ms","start":"2026-07-15T05:11:25.685668Z","end":"2026-07-15T05:11:25.982827Z","steps":["trace[67081951] 'agreement among raft nodes before linearized reading' (duration: 296.767434ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:31.704818Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022d5.wal"} {"level":"warn","ts":"2026-07-15T05:11:34.1194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.917649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:11:34.119533Z","caller":"traceutil/trace.go:171","msg":"trace[499165947] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8629; }","duration":"227.876115ms","start":"2026-07-15T05:11:33.891637Z","end":"2026-07-15T05:11:34.119513Z","steps":["trace[499165947] 'process raft request' (duration: 108.622587ms)","trace[499165947] 'compare' (duration: 118.698024ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:11:34.131092Z","caller":"traceutil/trace.go:171","msg":"trace[1018014220] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"238.086594ms","start":"2026-07-15T05:11:33.892989Z","end":"2026-07-15T05:11:34.131076Z","steps":["trace[1018014220] 'process raft request' (duration: 237.995202ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:34.131103Z","caller":"traceutil/trace.go:171","msg":"trace[20518584] linearizableReadLoop","detail":"{readStateIndex:8940; appliedIndex:8940; }","duration":"127.815576ms","start":"2026-07-15T05:11:34.00327Z","end":"2026-07-15T05:11:34.131085Z","steps":["trace[20518584] 'read index received' (duration: 127.807736ms)","trace[20518584] 'applied index is now lower than readState.Index' (duration: 6.18µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:11:34.131249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.921609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-c8pwr\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-07-15T05:11:34.131381Z","caller":"traceutil/trace.go:171","msg":"trace[769937679] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-c8pwr; range_end:; response_count:1; response_revision:8630; }","duration":"128.143224ms","start":"2026-07-15T05:11:34.00322Z","end":"2026-07-15T05:11:34.131363Z","steps":["trace[769937679] 'agreement among raft nodes before linearized reading' (duration: 127.923139ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:34.153497Z","caller":"traceutil/trace.go:171","msg":"trace[792918276] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"149.548055ms","start":"2026-07-15T05:11:34.003926Z","end":"2026-07-15T05:11:34.153474Z","steps":["trace[792918276] 'process raft request' (duration: 149.33532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:11:34.153651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.24639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-15T05:11:34.153719Z","caller":"traceutil/trace.go:171","msg":"trace[119054471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8632; }","duration":"138.339312ms","start":"2026-07-15T05:11:34.015354Z","end":"2026-07-15T05:11:34.153694Z","steps":["trace[119054471] 'agreement among raft nodes before linearized reading' (duration: 138.176858ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:34.153761Z","caller":"traceutil/trace.go:171","msg":"trace[1052529770] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"145.938667ms","start":"2026-07-15T05:11:34.007773Z","end":"2026-07-15T05:11:34.153712Z","steps":["trace[1052529770] 'process raft request' (duration: 145.601379ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:34.69433Z","caller":"traceutil/trace.go:171","msg":"trace[1717838789] transaction","detail":"{read_only:false; response_revision:8639; number_of_response:1; }","duration":"141.514049ms","start":"2026-07-15T05:11:34.552763Z","end":"2026-07-15T05:11:34.694277Z","steps":["trace[1717838789] 'process raft request' (duration: 141.229482ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:11:34.917174Z","caller":"traceutil/trace.go:171","msg":"trace[1243358219] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"100.121671ms","start":"2026-07-15T05:11:34.817022Z","end":"2026-07-15T05:11:34.917144Z","steps":["trace[1243358219] 'process raft request' (duration: 99.922176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:12:52.81613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.793708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T05:12:52.816344Z","caller":"traceutil/trace.go:171","msg":"trace[1735600145] linearizableReadLoop","detail":"{readStateIndex:9730; appliedIndex:9729; }","duration":"118.815085ms","start":"2026-07-15T05:12:52.697445Z","end":"2026-07-15T05:12:52.81626Z","steps":["trace[1735600145] 'read index received' (duration: 66.141µs)","trace[1735600145] 'applied index is now lower than readState.Index' (duration: 118.747784ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:12:52.8164Z","caller":"traceutil/trace.go:171","msg":"trace[2115873453] transaction","detail":"{read_only:false; response_revision:9400; number_of_response:1; }","duration":"158.833732ms","start":"2026-07-15T05:12:52.657508Z","end":"2026-07-15T05:12:52.816341Z","steps":["trace[2115873453] 'process raft request' (duration: 11.694006ms)","trace[2115873453] 'compare' (duration: 146.581493ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:12:52.816616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.153434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hgrht\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-07-15T05:12:52.816676Z","caller":"traceutil/trace.go:171","msg":"trace[176806869] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hgrht; range_end:; response_count:1; response_revision:9400; }","duration":"119.291087ms","start":"2026-07-15T05:12:52.697371Z","end":"2026-07-15T05:12:52.816662Z","steps":["trace[176806869] 'agreement among raft nodes before linearized reading' (duration: 119.02746ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:12:56.300494Z","caller":"traceutil/trace.go:171","msg":"trace[422602163] transaction","detail":"{read_only:false; response_revision:9416; number_of_response:1; }","duration":"103.320089ms","start":"2026-07-15T05:12:56.197134Z","end":"2026-07-15T05:12:56.300454Z","steps":["trace[422602163] 'process raft request' (duration: 76.31277ms)","trace[422602163] 'compare' (duration: 26.715871ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:13:32.494067Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:13:32.501457Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-15T05:13:32.50164Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-15T05:15:40.330678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8254} {"level":"info","ts":"2026-07-15T05:15:40.417042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8254,"took":"83.576257ms","hash":2687130166,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":25190400,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-15T05:15:40.417132Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2687130166,"revision":8254,"compact-revision":6060} {"level":"info","ts":"2026-07-15T05:18:32.961427Z","caller":"traceutil/trace.go:171","msg":"trace[48768694] transaction","detail":"{read_only:false; response_revision:11837; number_of_response:1; }","duration":"205.215102ms","start":"2026-07-15T05:18:32.756184Z","end":"2026-07-15T05:18:32.961399Z","steps":["trace[48768694] 'process raft request' (duration: 205.046758ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:20:40.339943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10478} {"level":"info","ts":"2026-07-15T05:20:40.416106Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10478,"took":"72.483697ms","hash":126763596,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":26185728,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-15T05:20:40.416187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":126763596,"revision":10478,"compact-revision":8254} {"level":"info","ts":"2026-07-15T05:23:43.377304Z","caller":"traceutil/trace.go:171","msg":"trace[1865893510] linearizableReadLoop","detail":"{readStateIndex:14434; appliedIndex:14433; }","duration":"122.298491ms","start":"2026-07-15T05:23:43.254982Z","end":"2026-07-15T05:23:43.37728Z","steps":["trace[1865893510] 'read index received' (duration: 122.25692ms)","trace[1865893510] 'applied index is now lower than readState.Index' (duration: 40.601µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:23:43.377508Z","caller":"traceutil/trace.go:171","msg":"trace[1888652569] transaction","detail":"{read_only:false; response_revision:13952; number_of_response:1; }","duration":"203.023309ms","start":"2026-07-15T05:23:43.174476Z","end":"2026-07-15T05:23:43.377499Z","steps":["trace[1888652569] 'process raft request' (duration: 202.690011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:23:43.377676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.69024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:23:43.377704Z","caller":"traceutil/trace.go:171","msg":"trace[1973520403] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:13952; }","duration":"122.777002ms","start":"2026-07-15T05:23:43.25492Z","end":"2026-07-15T05:23:43.377697Z","steps":["trace[1973520403] 'agreement among raft nodes before linearized reading' (duration: 122.705971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:23:43.417491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.404172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:23:43.417585Z","caller":"traceutil/trace.go:171","msg":"trace[626557331] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:13953; }","duration":"117.639748ms","start":"2026-07-15T05:23:43.299924Z","end":"2026-07-15T05:23:43.417564Z","steps":["trace[626557331] 'agreement among raft nodes before linearized reading' (duration: 117.472684ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:23:43.417986Z","caller":"traceutil/trace.go:171","msg":"trace[2065054692] transaction","detail":"{read_only:false; response_revision:13953; number_of_response:1; }","duration":"126.778781ms","start":"2026-07-15T05:23:43.291153Z","end":"2026-07-15T05:23:43.417932Z","steps":["trace[2065054692] 'process raft request' (duration: 126.047542ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:13.465883Z","caller":"traceutil/trace.go:171","msg":"trace[1401712807] transaction","detail":"{read_only:false; response_revision:14511; number_of_response:1; }","duration":"119.436462ms","start":"2026-07-15T05:25:13.346417Z","end":"2026-07-15T05:25:13.465853Z","steps":["trace[1401712807] 'process raft request' (duration: 119.254968ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:14.504451Z","caller":"traceutil/trace.go:171","msg":"trace[2139827016] transaction","detail":"{read_only:false; response_revision:14517; number_of_response:1; }","duration":"112.063192ms","start":"2026-07-15T05:25:14.392358Z","end":"2026-07-15T05:25:14.504421Z","steps":["trace[2139827016] 'process raft request' (duration: 111.523739ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:23.568535Z","caller":"traceutil/trace.go:171","msg":"trace[64097259] linearizableReadLoop","detail":"{readStateIndex:15189; appliedIndex:15188; }","duration":"134.164981ms","start":"2026-07-15T05:25:23.43435Z","end":"2026-07-15T05:25:23.568515Z","steps":["trace[64097259] 'read index received' (duration: 134.009727ms)","trace[64097259] 'applied index is now lower than readState.Index' (duration: 154.263µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:25:23.568581Z","caller":"traceutil/trace.go:171","msg":"trace[421392560] transaction","detail":"{read_only:false; response_revision:14680; number_of_response:1; }","duration":"144.630405ms","start":"2026-07-15T05:25:23.423917Z","end":"2026-07-15T05:25:23.568548Z","steps":["trace[421392560] 'process raft request' (duration: 144.468161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:23.568684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.319724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:25:23.568709Z","caller":"traceutil/trace.go:171","msg":"trace[1599675061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14680; }","duration":"134.397736ms","start":"2026-07-15T05:25:23.434305Z","end":"2026-07-15T05:25:23.568703Z","steps":["trace[1599675061] 'agreement among raft nodes before linearized reading' (duration: 134.311904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:25:23.590699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.27733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-jxmfn\" ","response":"range_response_count:1 size:7031"} {"level":"info","ts":"2026-07-15T05:25:23.590759Z","caller":"traceutil/trace.go:171","msg":"trace[1398159440] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-jxmfn; range_end:; response_count:1; response_revision:14681; }","duration":"108.390702ms","start":"2026-07-15T05:25:23.482356Z","end":"2026-07-15T05:25:23.590747Z","steps":["trace[1398159440] 'agreement among raft nodes before linearized reading' (duration: 108.215378ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:23.732687Z","caller":"traceutil/trace.go:171","msg":"trace[787289400] linearizableReadLoop","detail":"{readStateIndex:15191; appliedIndex:15190; }","duration":"128.467392ms","start":"2026-07-15T05:25:23.604196Z","end":"2026-07-15T05:25:23.732663Z","steps":["trace[787289400] 'read index received' (duration: 128.242516ms)","trace[787289400] 'applied index is now lower than readState.Index' (duration: 223.976µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:25:23.73293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.715847ms","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-07-15T05:25:23.732971Z","caller":"traceutil/trace.go:171","msg":"trace[1747677065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14682; }","duration":"128.81287ms","start":"2026-07-15T05:25:23.604146Z","end":"2026-07-15T05:25:23.732959Z","steps":["trace[1747677065] 'agreement among raft nodes before linearized reading' (duration: 128.618135ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:23.733245Z","caller":"traceutil/trace.go:171","msg":"trace[1564130535] transaction","detail":"{read_only:false; response_revision:14682; number_of_response:1; }","duration":"132.770516ms","start":"2026-07-15T05:25:23.600463Z","end":"2026-07-15T05:25:23.733234Z","steps":["trace[1564130535] 'process raft request' (duration: 132.052149ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:25:40.350878Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12704} {"level":"info","ts":"2026-07-15T05:25:40.430948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12704,"took":"77.479098ms","hash":1111925776,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":29065216,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-15T05:25:40.431009Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1111925776,"revision":12704,"compact-revision":10478} {"level":"info","ts":"2026-07-15T05:27:02.33416Z","caller":"traceutil/trace.go:171","msg":"trace[271669567] linearizableReadLoop","detail":"{readStateIndex:16308; appliedIndex:16307; }","duration":"111.650712ms","start":"2026-07-15T05:27:02.222481Z","end":"2026-07-15T05:27:02.334132Z","steps":["trace[271669567] 'read index received' (duration: 111.551609ms)","trace[271669567] 'applied index is now lower than readState.Index' (duration: 98.543µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:27:02.334188Z","caller":"traceutil/trace.go:171","msg":"trace[196122210] transaction","detail":"{read_only:false; response_revision:15766; number_of_response:1; }","duration":"157.5517ms","start":"2026-07-15T05:27:02.176611Z","end":"2026-07-15T05:27:02.334163Z","steps":["trace[196122210] 'process raft request' (duration: 157.406347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:02.334333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.840956ms","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-07-15T05:27:02.334362Z","caller":"traceutil/trace.go:171","msg":"trace[1936922892] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15766; }","duration":"111.925738ms","start":"2026-07-15T05:27:02.222429Z","end":"2026-07-15T05:27:02.334354Z","steps":["trace[1936922892] 'agreement among raft nodes before linearized reading' (duration: 111.815045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:02.335845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.1885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T05:27:02.335879Z","caller":"traceutil/trace.go:171","msg":"trace[1483295562] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15767; }","duration":"113.259532ms","start":"2026-07-15T05:27:02.222612Z","end":"2026-07-15T05:27:02.335872Z","steps":["trace[1483295562] 'agreement among raft nodes before linearized reading' (duration: 113.18463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:02.335939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.756445ms","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-07-15T05:27:02.336045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.281257ms","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-07-15T05:27:02.336061Z","caller":"traceutil/trace.go:171","msg":"trace[1501044894] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15767; }","duration":"112.327398ms","start":"2026-07-15T05:27:02.223729Z","end":"2026-07-15T05:27:02.336056Z","steps":["trace[1501044894] 'agreement among raft nodes before linearized reading' (duration: 112.303217ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:27:02.336146Z","caller":"traceutil/trace.go:171","msg":"trace[2045555592] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15767; }","duration":"109.909219ms","start":"2026-07-15T05:27:02.226143Z","end":"2026-07-15T05:27:02.336052Z","steps":["trace[2045555592] 'agreement among raft nodes before linearized reading' (duration: 109.673633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:27:02.33602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.185681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-07-15T05:27:02.336416Z","caller":"traceutil/trace.go:171","msg":"trace[1842358228] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15767; }","duration":"111.594ms","start":"2026-07-15T05:27:02.224813Z","end":"2026-07-15T05:27:02.336407Z","steps":["trace[1842358228] 'agreement among raft nodes before linearized reading' (duration: 111.16378ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:30:40.356585Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14987} {"level":"info","ts":"2026-07-15T05:30:40.451771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14987,"took":"92.131424ms","hash":419055057,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30662656,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T05:30:40.451922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":419055057,"revision":14987,"compact-revision":12704} {"level":"info","ts":"2026-07-15T05:32:41.010676Z","caller":"traceutil/trace.go:171","msg":"trace[1443778853] linearizableReadLoop","detail":"{readStateIndex:18888; appliedIndex:18887; }","duration":"172.29178ms","start":"2026-07-15T05:32:40.838364Z","end":"2026-07-15T05:32:41.010656Z","steps":["trace[1443778853] 'read index received' (duration: 172.079534ms)","trace[1443778853] 'applied index is now lower than readState.Index' (duration: 211.566µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:32:41.01078Z","caller":"traceutil/trace.go:171","msg":"trace[236280414] transaction","detail":"{read_only:false; response_revision:18263; number_of_response:1; }","duration":"214.843212ms","start":"2026-07-15T05:32:40.795926Z","end":"2026-07-15T05:32:41.010769Z","steps":["trace[236280414] 'process raft request' (duration: 214.599115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:41.010968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.606427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:32:41.010998Z","caller":"traceutil/trace.go:171","msg":"trace[1311525550] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18263; }","duration":"172.652618ms","start":"2026-07-15T05:32:40.838338Z","end":"2026-07-15T05:32:41.010991Z","steps":["trace[1311525550] 'agreement among raft nodes before linearized reading' (duration: 172.539025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:41.011071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.261943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:32:41.011187Z","caller":"traceutil/trace.go:171","msg":"trace[528344300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18263; }","duration":"166.375817ms","start":"2026-07-15T05:32:40.844762Z","end":"2026-07-15T05:32:41.011138Z","steps":["trace[528344300] 'agreement among raft nodes before linearized reading' (duration: 166.169122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:41.011219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.271374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T05:32:41.011301Z","caller":"traceutil/trace.go:171","msg":"trace[1283169982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18263; }","duration":"166.414707ms","start":"2026-07-15T05:32:40.844868Z","end":"2026-07-15T05:32:41.011282Z","steps":["trace[1283169982] 'agreement among raft nodes before linearized reading' (duration: 166.152521ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:43.123267Z","caller":"traceutil/trace.go:171","msg":"trace[1272797991] linearizableReadLoop","detail":"{readStateIndex:18915; appliedIndex:18914; }","duration":"101.618795ms","start":"2026-07-15T05:32:43.021618Z","end":"2026-07-15T05:32:43.123237Z","steps":["trace[1272797991] 'read index received' (duration: 97.592727ms)","trace[1272797991] 'applied index is now lower than readState.Index' (duration: 4.025058ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:32:43.123338Z","caller":"traceutil/trace.go:171","msg":"trace[27627581] transaction","detail":"{read_only:false; response_revision:18290; number_of_response:1; }","duration":"103.969252ms","start":"2026-07-15T05:32:43.019311Z","end":"2026-07-15T05:32:43.123281Z","steps":["trace[27627581] 'process raft request' (duration: 100.010815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:43.123493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.855151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:32:43.123561Z","caller":"traceutil/trace.go:171","msg":"trace[1188377216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18290; }","duration":"101.956614ms","start":"2026-07-15T05:32:43.02159Z","end":"2026-07-15T05:32:43.123547Z","steps":["trace[1188377216] 'agreement among raft nodes before linearized reading' (duration: 101.759469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:43.123541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.81577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:32:43.123611Z","caller":"traceutil/trace.go:171","msg":"trace[1418426708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18290; }","duration":"101.915923ms","start":"2026-07-15T05:32:43.021679Z","end":"2026-07-15T05:32:43.123594Z","steps":["trace[1418426708] 'agreement among raft nodes before linearized reading' (duration: 101.709618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:43.123764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.022965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T05:32:43.123823Z","caller":"traceutil/trace.go:171","msg":"trace[1398193475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18290; }","duration":"102.180259ms","start":"2026-07-15T05:32:43.02163Z","end":"2026-07-15T05:32:43.12381Z","steps":["trace[1398193475] 'agreement among raft nodes before linearized reading' (duration: 101.948314ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:32:48.511666Z","caller":"traceutil/trace.go:171","msg":"trace[1518999500] transaction","detail":"{read_only:false; response_revision:18332; number_of_response:1; }","duration":"412.680591ms","start":"2026-07-15T05:32:48.098963Z","end":"2026-07-15T05:32:48.511644Z","steps":["trace[1518999500] 'process raft request' (duration: 412.547337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:32:48.511832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:32:48.098894Z","time spent":"412.861514ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:32:48.566909Z","caller":"traceutil/trace.go:171","msg":"trace[979248962] linearizableReadLoop","detail":"{readStateIndex:18959; appliedIndex:18958; }","duration":"133.318884ms","start":"2026-07-15T05:32:48.433556Z","end":"2026-07-15T05:32:48.566875Z","steps":["trace[979248962] 'read index received' (duration: 78.077643ms)","trace[979248962] 'applied index is now lower than readState.Index' (duration: 55.238751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:32:48.593402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.836117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:32:48.593469Z","caller":"traceutil/trace.go:171","msg":"trace[1644463473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18333; }","duration":"159.937439ms","start":"2026-07-15T05:32:48.433515Z","end":"2026-07-15T05:32:48.593453Z","steps":["trace[1644463473] 'agreement among raft nodes before linearized reading' (duration: 133.474507ms)","trace[1644463473] 'range keys from in-memory index tree' (duration: 26.362739ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:34:29.137841Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:34:29.146599Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-15T05:34:29.146793Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-15T05:35:40.363261Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17427} {"level":"info","ts":"2026-07-15T05:35:40.458251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17427,"took":"91.248863ms","hash":1684335619,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":34508800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T05:35:40.458314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1684335619,"revision":17427,"compact-revision":14987} {"level":"info","ts":"2026-07-15T05:38:59.154639Z","caller":"traceutil/trace.go:171","msg":"trace[415059217] transaction","detail":"{read_only:false; response_revision:21135; number_of_response:1; }","duration":"145.356716ms","start":"2026-07-15T05:38:59.009257Z","end":"2026-07-15T05:38:59.154614Z","steps":["trace[415059217] 'process raft request' (duration: 145.173791ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:04.741953Z","caller":"traceutil/trace.go:171","msg":"trace[1333900349] transaction","detail":"{read_only:false; response_revision:21161; number_of_response:1; }","duration":"263.223015ms","start":"2026-07-15T05:39:04.478687Z","end":"2026-07-15T05:39:04.74191Z","steps":["trace[1333900349] 'process raft request' (duration: 262.975579ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:04.882304Z","caller":"traceutil/trace.go:171","msg":"trace[376614802] transaction","detail":"{read_only:false; response_revision:21162; number_of_response:1; }","duration":"252.942896ms","start":"2026-07-15T05:39:04.629322Z","end":"2026-07-15T05:39:04.882265Z","steps":["trace[376614802] 'process raft request' (duration: 233.843412ms)","trace[376614802] 'compare' (duration: 18.753565ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:39:04.88236Z","caller":"traceutil/trace.go:171","msg":"trace[1484889100] linearizableReadLoop","detail":"{readStateIndex:21882; appliedIndex:21880; }","duration":"196.870756ms","start":"2026-07-15T05:39:04.685402Z","end":"2026-07-15T05:39:04.882272Z","steps":["trace[1484889100] 'read index received' (duration: 56.242405ms)","trace[1484889100] 'applied index is now lower than readState.Index' (duration: 140.626521ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:39:04.882563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.121832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:39:04.882687Z","caller":"traceutil/trace.go:171","msg":"trace[381367617] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:21162; }","duration":"197.298826ms","start":"2026-07-15T05:39:04.685359Z","end":"2026-07-15T05:39:04.882657Z","steps":["trace[381367617] 'agreement among raft nodes before linearized reading' (duration: 197.108751ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:05.216202Z","caller":"traceutil/trace.go:171","msg":"trace[172095776] linearizableReadLoop","detail":"{readStateIndex:21884; appliedIndex:21882; }","duration":"156.847634ms","start":"2026-07-15T05:39:05.059329Z","end":"2026-07-15T05:39:05.216177Z","steps":["trace[172095776] 'read index received' (duration: 14.412549ms)","trace[172095776] 'applied index is now lower than readState.Index' (duration: 142.432335ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:39:05.216211Z","caller":"traceutil/trace.go:171","msg":"trace[1436867210] transaction","detail":"{read_only:false; response_revision:21164; number_of_response:1; }","duration":"172.888283ms","start":"2026-07-15T05:39:05.04327Z","end":"2026-07-15T05:39:05.216158Z","steps":["trace[1436867210] 'process raft request' (duration: 157.984362ms)","trace[1436867210] 'compare' (duration: 14.639195ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:39:05.216441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.08009ms","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-07-15T05:39:05.216641Z","caller":"traceutil/trace.go:171","msg":"trace[2098694826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21165; }","duration":"157.335687ms","start":"2026-07-15T05:39:05.059282Z","end":"2026-07-15T05:39:05.216618Z","steps":["trace[2098694826] 'agreement among raft nodes before linearized reading' (duration: 156.990909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:39:05.576802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.66323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:39:05.577252Z","caller":"traceutil/trace.go:171","msg":"trace[487547639] transaction","detail":"{read_only:false; response_revision:21167; number_of_response:1; }","duration":"355.840792ms","start":"2026-07-15T05:39:05.221391Z","end":"2026-07-15T05:39:05.577232Z","steps":["trace[487547639] 'process raft request' (duration: 355.519104ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:05.577313Z","caller":"traceutil/trace.go:171","msg":"trace[1560236868] transaction","detail":"{read_only:false; response_revision:21166; number_of_response:1; }","duration":"356.015916ms","start":"2026-07-15T05:39:05.22125Z","end":"2026-07-15T05:39:05.577266Z","steps":["trace[1560236868] 'process raft request' (duration: 151.793132ms)","trace[1560236868] 'compare' (duration: 203.437465ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:39:05.577508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:39:05.221225Z","time spent":"356.17964ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:39:05.577675Z","caller":"traceutil/trace.go:171","msg":"trace[1466001849] transaction","detail":"{read_only:false; response_revision:21168; number_of_response:1; }","duration":"355.983525ms","start":"2026-07-15T05:39:05.221629Z","end":"2026-07-15T05:39:05.577613Z","steps":["trace[1466001849] 'process raft request' (duration: 355.549705ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:39:05.577772Z","caller":"traceutil/trace.go:171","msg":"trace[1491086404] transaction","detail":"{read_only:false; response_revision:21169; number_of_response:1; }","duration":"354.989211ms","start":"2026-07-15T05:39:05.222741Z","end":"2026-07-15T05:39:05.57773Z","steps":["trace[1491086404] 'process raft request' (duration: 354.751805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:39:05.577742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:39:05.221367Z","time spent":"355.937175ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:39:05.577855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.335474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:39:05.577706Z","caller":"traceutil/trace.go:171","msg":"trace[2062750748] linearizableReadLoop","detail":"{readStateIndex:21889; appliedIndex:21885; }","duration":"140.16522ms","start":"2026-07-15T05:39:05.437499Z","end":"2026-07-15T05:39:05.577664Z","steps":["trace[2062750748] 'read index received' (duration: 138.095701ms)","trace[2062750748] 'applied index is now lower than readState.Index' (duration: 2.068139ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:39:05.577918Z","caller":"traceutil/trace.go:171","msg":"trace[1985000042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21169; }","duration":"140.471557ms","start":"2026-07-15T05:39:05.437432Z","end":"2026-07-15T05:39:05.577903Z","steps":["trace[1985000042] 'agreement among raft nodes before linearized reading' (duration: 140.350194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:39:05.577985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:39:05.222731Z","time spent":"355.134814ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:39:05.578115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:39:05.221605Z","time spent":"356.236861ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:40:40.368857Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19810} {"level":"info","ts":"2026-07-15T05:40:40.479208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19810,"took":"105.993252ms","hash":104563281,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":30019584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T05:40:40.479265Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":104563281,"revision":19810,"compact-revision":17427} {"level":"info","ts":"2026-07-15T05:43:02.460711Z","caller":"traceutil/trace.go:171","msg":"trace[962493889] transaction","detail":"{read_only:false; response_revision:23643; number_of_response:1; }","duration":"227.914685ms","start":"2026-07-15T05:43:02.232765Z","end":"2026-07-15T05:43:02.46068Z","steps":["trace[962493889] 'process raft request' (duration: 200.274244ms)","trace[962493889] 'compare' (duration: 27.382175ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:45:40.37708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22011} {"level":"info","ts":"2026-07-15T05:45:40.48183Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22011,"took":"100.728066ms","hash":117449779,"current-db-size-bytes":59355136,"current-db-size":"59 MB","current-db-size-in-use-bytes":45023232,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-07-15T05:45:40.481892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":117449779,"revision":22011,"compact-revision":19810} {"level":"info","ts":"2026-07-15T05:46:07.095757Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000066b9.wal"} {"level":"info","ts":"2026-07-15T05:46:18.428553Z","caller":"traceutil/trace.go:171","msg":"trace[467779316] linearizableReadLoop","detail":"{readStateIndex:26510; appliedIndex:26509; }","duration":"184.847848ms","start":"2026-07-15T05:46:18.243675Z","end":"2026-07-15T05:46:18.428523Z","steps":["trace[467779316] 'read index received' (duration: 184.714905ms)","trace[467779316] 'applied index is now lower than readState.Index' (duration: 131.953µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:18.428648Z","caller":"traceutil/trace.go:171","msg":"trace[1315258586] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"207.734315ms","start":"2026-07-15T05:46:18.220877Z","end":"2026-07-15T05:46:18.428612Z","steps":["trace[1315258586] 'process raft request' (duration: 207.467688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:18.428717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.016543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:18.428757Z","caller":"traceutil/trace.go:171","msg":"trace[1955182381] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25660; }","duration":"185.113395ms","start":"2026-07-15T05:46:18.243632Z","end":"2026-07-15T05:46:18.428745Z","steps":["trace[1955182381] 'agreement among raft nodes before linearized reading' (duration: 185.025263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:18.867051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.236294ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T05:46:18.86836Z","caller":"traceutil/trace.go:171","msg":"trace[1999904679] linearizableReadLoop","detail":"{readStateIndex:26512; appliedIndex:26510; }","duration":"439.700738ms","start":"2026-07-15T05:46:18.428634Z","end":"2026-07-15T05:46:18.868335Z","steps":["trace[1999904679] 'read index received' (duration: 151.236603ms)","trace[1999904679] 'applied index is now lower than readState.Index' (duration: 288.462855ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:18.868548Z","caller":"traceutil/trace.go:171","msg":"trace[567070408] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"494.803827ms","start":"2026-07-15T05:46:18.373731Z","end":"2026-07-15T05:46:18.868535Z","steps":["trace[567070408] 'process raft request' (duration: 493.448764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:18.868669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:18.37371Z","time spent":"494.867588ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:19.016262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.268387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:19.016367Z","caller":"traceutil/trace.go:171","msg":"trace[1757433685] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25661; }","duration":"607.42272ms","start":"2026-07-15T05:46:18.40892Z","end":"2026-07-15T05:46:19.016342Z","steps":["trace[1757433685] 'agreement among raft nodes before linearized reading' (duration: 459.60773ms)","trace[1757433685] 'count revisions from in-memory index tree' (duration: 147.628426ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:19.016443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:18.408905Z","time spent":"607.525883ms","remote":"127.0.0.1:39198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:46:19.016709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.324143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:46:19.016839Z","caller":"traceutil/trace.go:171","msg":"trace[1549120430] transaction","detail":"{read_only:false; response_revision:25663; number_of_response:1; }","duration":"500.497774ms","start":"2026-07-15T05:46:18.516332Z","end":"2026-07-15T05:46:19.016829Z","steps":["trace[1549120430] 'process raft request' (duration: 500.437793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:19.016875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:18.516308Z","time spent":"500.549936ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:19.016996Z","caller":"traceutil/trace.go:171","msg":"trace[885202955] transaction","detail":"{read_only:false; response_revision:25662; number_of_response:1; }","duration":"554.452024ms","start":"2026-07-15T05:46:18.462538Z","end":"2026-07-15T05:46:19.01699Z","steps":["trace[885202955] 'process raft request' (duration: 405.787984ms)","trace[885202955] 'compare' (duration: 147.958783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:19.017023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:18.462517Z","time spent":"554.490925ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:19.017189Z","caller":"traceutil/trace.go:171","msg":"trace[98169751] transaction","detail":"{read_only:false; response_revision:25664; number_of_response:1; }","duration":"500.509635ms","start":"2026-07-15T05:46:18.516647Z","end":"2026-07-15T05:46:19.017157Z","steps":["trace[98169751] 'process raft request' (duration: 500.157056ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:19.01731Z","caller":"traceutil/trace.go:171","msg":"trace[1697638154] transaction","detail":"{read_only:false; response_revision:25665; number_of_response:1; }","duration":"307.593139ms","start":"2026-07-15T05:46:18.709708Z","end":"2026-07-15T05:46:19.017301Z","steps":["trace[1697638154] 'process raft request' (duration: 307.327813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:19.017351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:18.709673Z","time spent":"307.656831ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:19.017412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:18.516626Z","time spent":"500.661898ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:19.079334Z","caller":"traceutil/trace.go:171","msg":"trace[1643331181] linearizableReadLoop","detail":"{readStateIndex:26517; appliedIndex:26516; }","duration":"210.814649ms","start":"2026-07-15T05:46:18.868492Z","end":"2026-07-15T05:46:19.079307Z","steps":["trace[1643331181] 'read index received' (duration: 210.576683ms)","trace[1643331181] 'applied index is now lower than readState.Index' (duration: 237.166µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:19.079489Z","caller":"traceutil/trace.go:171","msg":"trace[247269057] transaction","detail":"{read_only:false; response_revision:25666; number_of_response:1; }","duration":"217.228015ms","start":"2026-07-15T05:46:18.862226Z","end":"2026-07-15T05:46:19.079454Z","steps":["trace[247269057] 'process raft request' (duration: 216.943598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:19.079616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.871151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:19.079691Z","caller":"traceutil/trace.go:171","msg":"trace[1967909093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25666; }","duration":"642.987054ms","start":"2026-07-15T05:46:18.436684Z","end":"2026-07-15T05:46:19.079671Z","steps":["trace[1967909093] 'agreement among raft nodes before linearized reading' (duration: 642.862001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:19.079722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:18.436668Z","time spent":"643.047475ms","remote":"127.0.0.1:38932","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-07-15T05:46:19.079886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.804626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:46:19.079947Z","caller":"traceutil/trace.go:171","msg":"trace[1105641696] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25666; }","duration":"242.906469ms","start":"2026-07-15T05:46:18.837033Z","end":"2026-07-15T05:46:19.079939Z","steps":["trace[1105641696] 'agreement among raft nodes before linearized reading' (duration: 242.820106ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:19.200756Z","caller":"traceutil/trace.go:171","msg":"trace[1146231762] transaction","detail":"{read_only:false; response_revision:25667; number_of_response:1; }","duration":"116.92429ms","start":"2026-07-15T05:46:19.083805Z","end":"2026-07-15T05:46:19.20073Z","steps":["trace[1146231762] 'process raft request' (duration: 75.241928ms)","trace[1146231762] 'compare' (duration: 41.106487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:19.582684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.189274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:19.582776Z","caller":"traceutil/trace.go:171","msg":"trace[859965319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25667; }","duration":"147.334578ms","start":"2026-07-15T05:46:19.435422Z","end":"2026-07-15T05:46:19.582757Z","steps":["trace[859965319] 'range keys from in-memory index tree' (duration: 147.071022ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:19.72745Z","caller":"traceutil/trace.go:171","msg":"trace[894282542] transaction","detail":"{read_only:false; response_revision:25668; number_of_response:1; }","duration":"223.265573ms","start":"2026-07-15T05:46:19.504161Z","end":"2026-07-15T05:46:19.727426Z","steps":["trace[894282542] 'process raft request' (duration: 223.028417ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:19.727375Z","caller":"traceutil/trace.go:171","msg":"trace[1046238565] linearizableReadLoop","detail":"{readStateIndex:26519; appliedIndex:26518; }","duration":"131.499064ms","start":"2026-07-15T05:46:19.595843Z","end":"2026-07-15T05:46:19.727342Z","steps":["trace[1046238565] 'read index received' (duration: 131.230857ms)","trace[1046238565] 'applied index is now lower than readState.Index' (duration: 267.187µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:19.727674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.79789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:19.727787Z","caller":"traceutil/trace.go:171","msg":"trace[1341351038] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25668; }","duration":"131.935193ms","start":"2026-07-15T05:46:19.595824Z","end":"2026-07-15T05:46:19.727759Z","steps":["trace[1341351038] 'agreement among raft nodes before linearized reading' (duration: 131.716958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:19.994973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.339541ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:46:19.995196Z","caller":"traceutil/trace.go:171","msg":"trace[1011620435] linearizableReadLoop","detail":"{readStateIndex:26521; appliedIndex:26519; }","duration":"257.860672ms","start":"2026-07-15T05:46:19.737315Z","end":"2026-07-15T05:46:19.995176Z","steps":["trace[1011620435] 'read index received' (duration: 128.177743ms)","trace[1011620435] 'applied index is now lower than readState.Index' (duration: 129.681629ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:19.995319Z","caller":"traceutil/trace.go:171","msg":"trace[929564413] transaction","detail":"{read_only:false; response_revision:25670; number_of_response:1; }","duration":"260.596098ms","start":"2026-07-15T05:46:19.734696Z","end":"2026-07-15T05:46:19.995292Z","steps":["trace[929564413] 'process raft request' (duration: 260.388443ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:19.995344Z","caller":"traceutil/trace.go:171","msg":"trace[1269510195] transaction","detail":"{read_only:false; response_revision:25669; number_of_response:1; }","duration":"261.186993ms","start":"2026-07-15T05:46:19.734135Z","end":"2026-07-15T05:46:19.995322Z","steps":["trace[1269510195] 'process raft request' (duration: 131.400201ms)","trace[1269510195] 'compare' (duration: 129.047384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:19.995392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.067947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T05:46:19.995426Z","caller":"traceutil/trace.go:171","msg":"trace[440929302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25670; }","duration":"258.114948ms","start":"2026-07-15T05:46:19.737299Z","end":"2026-07-15T05:46:19.995414Z","steps":["trace[440929302] 'agreement among raft nodes before linearized reading' (duration: 257.984395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:19.995639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.036466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:46:19.995744Z","caller":"traceutil/trace.go:171","msg":"trace[193220964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25670; }","duration":"258.2009ms","start":"2026-07-15T05:46:19.737517Z","end":"2026-07-15T05:46:19.995718Z","steps":["trace[193220964] 'agreement among raft nodes before linearized reading' (duration: 257.852982ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:25.281934Z","caller":"traceutil/trace.go:171","msg":"trace[648333246] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"395.530354ms","start":"2026-07-15T05:46:24.886377Z","end":"2026-07-15T05:46:25.281907Z","steps":["trace[648333246] 'process raft request' (duration: 395.32855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:25.282156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:24.886353Z","time spent":"395.679378ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:25.69418Z","caller":"traceutil/trace.go:171","msg":"trace[860981280] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"651.904081ms","start":"2026-07-15T05:46:25.042256Z","end":"2026-07-15T05:46:25.69416Z","steps":["trace[860981280] 'process raft request' (duration: 651.85455ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:25.694234Z","caller":"traceutil/trace.go:171","msg":"trace[913075505] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"654.003382ms","start":"2026-07-15T05:46:25.040174Z","end":"2026-07-15T05:46:25.694177Z","steps":["trace[913075505] 'process raft request' (duration: 653.776755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:25.694301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:25.042229Z","time spent":"652.007483ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:25.694481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:25.040145Z","time spent":"654.195867ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:25.694556Z","caller":"traceutil/trace.go:171","msg":"trace[534855591] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"642.37882ms","start":"2026-07-15T05:46:25.052152Z","end":"2026-07-15T05:46:25.694531Z","steps":["trace[534855591] 'process raft request' (duration: 641.983391ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:25.694626Z","caller":"traceutil/trace.go:171","msg":"trace[655550928] linearizableReadLoop","detail":"{readStateIndex:26579; appliedIndex:26573; }","duration":"474.880792ms","start":"2026-07-15T05:46:25.219737Z","end":"2026-07-15T05:46:25.694618Z","steps":["trace[655550928] 'read index received' (duration: 62.030806ms)","trace[655550928] 'applied index is now lower than readState.Index' (duration: 412.849456ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:25.694629Z","caller":"traceutil/trace.go:171","msg":"trace[184443559] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"654.312529ms","start":"2026-07-15T05:46:25.040256Z","end":"2026-07-15T05:46:25.694568Z","steps":["trace[184443559] 'process raft request' (duration: 653.826668ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:25.694711Z","caller":"traceutil/trace.go:171","msg":"trace[314373806] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"598.698639ms","start":"2026-07-15T05:46:25.096006Z","end":"2026-07-15T05:46:25.694704Z","steps":["trace[314373806] 'process raft request' (duration: 598.410383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:25.694703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:25.052136Z","time spent":"642.491693ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:25.694747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:25.095977Z","time spent":"598.75363ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:25.69485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:25.040214Z","time spent":"654.515234ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:25.694979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.244851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T05:46:25.695021Z","caller":"traceutil/trace.go:171","msg":"trace[161383243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25727; }","duration":"475.315733ms","start":"2026-07-15T05:46:25.219694Z","end":"2026-07-15T05:46:25.69501Z","steps":["trace[161383243] 'agreement among raft nodes before linearized reading' (duration: 475.18428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:25.695059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:25.219677Z","time spent":"475.374815ms","remote":"127.0.0.1:39158","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-07-15T05:46:25.695239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.153917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:25.695445Z","caller":"traceutil/trace.go:171","msg":"trace[1757442314] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25727; }","duration":"344.560807ms","start":"2026-07-15T05:46:25.35087Z","end":"2026-07-15T05:46:25.695431Z","steps":["trace[1757442314] 'agreement among raft nodes before linearized reading' (duration: 343.944112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:25.695672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:25.350849Z","time spent":"344.812583ms","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:46:25.854287Z","caller":"traceutil/trace.go:171","msg":"trace[360602826] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"153.745893ms","start":"2026-07-15T05:46:25.700512Z","end":"2026-07-15T05:46:25.854258Z","steps":["trace[360602826] 'process raft request' (duration: 133.016309ms)","trace[360602826] 'compare' (duration: 20.595431ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:25.915105Z","caller":"traceutil/trace.go:171","msg":"trace[706012473] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"169.049835ms","start":"2026-07-15T05:46:25.746034Z","end":"2026-07-15T05:46:25.915083Z","steps":["trace[706012473] 'process raft request' (duration: 168.617184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.227382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.298458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-15T05:46:26.227436Z","caller":"traceutil/trace.go:171","msg":"trace[825573555] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25729; }","duration":"138.39065ms","start":"2026-07-15T05:46:26.089033Z","end":"2026-07-15T05:46:26.227423Z","steps":["trace[825573555] 'range keys from in-memory index tree' (duration: 138.140985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.22759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.558378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-15T05:46:26.227606Z","caller":"traceutil/trace.go:171","msg":"trace[2088114759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25729; }","duration":"127.613689ms","start":"2026-07-15T05:46:26.099988Z","end":"2026-07-15T05:46:26.227602Z","steps":["trace[2088114759] 'range keys from in-memory index tree' (duration: 127.444765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.227645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.347164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-15T05:46:26.227705Z","caller":"traceutil/trace.go:171","msg":"trace[736247112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25729; }","duration":"209.427946ms","start":"2026-07-15T05:46:26.018258Z","end":"2026-07-15T05:46:26.227686Z","steps":["trace[736247112] 'range keys from in-memory index tree' (duration: 209.17014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.227737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.708307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:46:26.227755Z","caller":"traceutil/trace.go:171","msg":"trace[999049050] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25729; }","duration":"110.729429ms","start":"2026-07-15T05:46:26.117021Z","end":"2026-07-15T05:46:26.22775Z","steps":["trace[999049050] 'count revisions from in-memory index tree' (duration: 110.650836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.227373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.710379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T05:46:26.227928Z","caller":"traceutil/trace.go:171","msg":"trace[2110614202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25729; }","duration":"158.311594ms","start":"2026-07-15T05:46:26.069609Z","end":"2026-07-15T05:46:26.22792Z","steps":["trace[2110614202] 'range keys from in-memory index tree' (duration: 157.557406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.227947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.845435ms","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-07-15T05:46:26.227985Z","caller":"traceutil/trace.go:171","msg":"trace[860312235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25729; }","duration":"202.919467ms","start":"2026-07-15T05:46:26.025055Z","end":"2026-07-15T05:46:26.227974Z","steps":["trace[860312235] 'range keys from in-memory index tree' (duration: 202.727922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.22826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.361925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:26.228284Z","caller":"traceutil/trace.go:171","msg":"trace[518437539] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25729; }","duration":"186.407927ms","start":"2026-07-15T05:46:26.041869Z","end":"2026-07-15T05:46:26.228277Z","steps":["trace[518437539] 'count revisions from in-memory index tree' (duration: 186.275463ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:26.402813Z","caller":"traceutil/trace.go:171","msg":"trace[1287935569] linearizableReadLoop","detail":"{readStateIndex:26582; appliedIndex:26581; }","duration":"166.917194ms","start":"2026-07-15T05:46:26.235879Z","end":"2026-07-15T05:46:26.402796Z","steps":["trace[1287935569] 'read index received' (duration: 166.859723ms)","trace[1287935569] 'applied index is now lower than readState.Index' (duration: 56.971µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:26.402933Z","caller":"traceutil/trace.go:171","msg":"trace[1691674333] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"167.117689ms","start":"2026-07-15T05:46:26.235785Z","end":"2026-07-15T05:46:26.402902Z","steps":["trace[1691674333] 'process raft request' (duration: 166.895973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.402962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.064378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-15T05:46:26.402984Z","caller":"traceutil/trace.go:171","msg":"trace[158644720] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25730; }","duration":"167.13768ms","start":"2026-07-15T05:46:26.235842Z","end":"2026-07-15T05:46:26.402979Z","steps":["trace[158644720] 'agreement among raft nodes before linearized reading' (duration: 167.019797ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:26.64281Z","caller":"traceutil/trace.go:171","msg":"trace[176899506] transaction","detail":"{read_only:false; response_revision:25731; number_of_response:1; }","duration":"406.503972ms","start":"2026-07-15T05:46:26.236282Z","end":"2026-07-15T05:46:26.642786Z","steps":["trace[176899506] 'process raft request' (duration: 406.287256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.642981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:26.236272Z","time spent":"406.615994ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:26.643117Z","caller":"traceutil/trace.go:171","msg":"trace[415309486] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"406.737608ms","start":"2026-07-15T05:46:26.236347Z","end":"2026-07-15T05:46:26.643084Z","steps":["trace[415309486] 'process raft request' (duration: 406.390029ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:26.643147Z","caller":"traceutil/trace.go:171","msg":"trace[772460602] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"406.710197ms","start":"2026-07-15T05:46:26.236417Z","end":"2026-07-15T05:46:26.643127Z","steps":["trace[772460602] 'process raft request' (duration: 406.559363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.643271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:26.236411Z","time spent":"406.794099ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:26.643278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:26.236342Z","time spent":"406.85179ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:46:26.643296Z","caller":"traceutil/trace.go:171","msg":"trace[837970693] linearizableReadLoop","detail":"{readStateIndex:26586; appliedIndex:26582; }","duration":"239.039054ms","start":"2026-07-15T05:46:26.404236Z","end":"2026-07-15T05:46:26.643275Z","steps":["trace[837970693] 'read index received' (duration: 238.315677ms)","trace[837970693] 'applied index is now lower than readState.Index' (duration: 722.157µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:26.643381Z","caller":"traceutil/trace.go:171","msg":"trace[968394927] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"327.465072ms","start":"2026-07-15T05:46:26.315908Z","end":"2026-07-15T05:46:26.643373Z","steps":["trace[968394927] 'process raft request' (duration: 327.178835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.643421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:26.315887Z","time spent":"327.510293ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:26.643509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.262729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18431"} {"level":"info","ts":"2026-07-15T05:46:26.643599Z","caller":"traceutil/trace.go:171","msg":"trace[1949891526] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25734; }","duration":"239.393043ms","start":"2026-07-15T05:46:26.404191Z","end":"2026-07-15T05:46:26.643584Z","steps":["trace[1949891526] 'agreement among raft nodes before linearized reading' (duration: 239.186478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.705108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.877462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:26.705174Z","caller":"traceutil/trace.go:171","msg":"trace[458637791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25735; }","duration":"271.984135ms","start":"2026-07-15T05:46:26.433175Z","end":"2026-07-15T05:46:26.70516Z","steps":["trace[458637791] 'agreement among raft nodes before linearized reading' (duration: 271.823411ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:26.705536Z","caller":"traceutil/trace.go:171","msg":"trace[1369477069] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"244.913558ms","start":"2026-07-15T05:46:26.460613Z","end":"2026-07-15T05:46:26.705526Z","steps":["trace[1369477069] 'process raft request' (duration: 244.279822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:26.705762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.845902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:26.705814Z","caller":"traceutil/trace.go:171","msg":"trace[2068319835] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25735; }","duration":"215.931263ms","start":"2026-07-15T05:46:26.489866Z","end":"2026-07-15T05:46:26.705798Z","steps":["trace[2068319835] 'agreement among raft nodes before linearized reading' (duration: 215.815ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:34.584048Z","caller":"traceutil/trace.go:171","msg":"trace[521811527] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"143.884324ms","start":"2026-07-15T05:46:34.440136Z","end":"2026-07-15T05:46:34.58402Z","steps":["trace[521811527] 'process raft request' (duration: 143.689759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:34.806293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.612763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:46:34.806361Z","caller":"traceutil/trace.go:171","msg":"trace[1866555582] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25799; }","duration":"128.696235ms","start":"2026-07-15T05:46:34.677649Z","end":"2026-07-15T05:46:34.806345Z","steps":["trace[1866555582] 'range keys from in-memory index tree' (duration: 128.431529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:34.806376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.418182ms","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:6"} {"level":"warn","ts":"2026-07-15T05:46:34.806395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.962447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:46:34.806553Z","caller":"traceutil/trace.go:171","msg":"trace[562111180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25799; }","duration":"128.159082ms","start":"2026-07-15T05:46:34.678374Z","end":"2026-07-15T05:46:34.806533Z","steps":["trace[562111180] 'range keys from in-memory index tree' (duration: 127.836634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:34.806588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.008227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T05:46:34.806682Z","caller":"traceutil/trace.go:171","msg":"trace[772497815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25799; }","duration":"128.153032ms","start":"2026-07-15T05:46:34.6785Z","end":"2026-07-15T05:46:34.806653Z","steps":["trace[772497815] 'range keys from in-memory index tree' (duration: 127.838463ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:34.806474Z","caller":"traceutil/trace.go:171","msg":"trace[1550187338] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25799; }","duration":"108.562006ms","start":"2026-07-15T05:46:34.69789Z","end":"2026-07-15T05:46:34.806452Z","steps":["trace[1550187338] 'count revisions from in-memory index tree' (duration: 108.35479ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:35.424073Z","caller":"traceutil/trace.go:171","msg":"trace[611318862] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"105.679276ms","start":"2026-07-15T05:46:35.318373Z","end":"2026-07-15T05:46:35.424053Z","steps":["trace[611318862] 'process raft request' (duration: 105.520602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.10108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.883981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:46:43.101194Z","caller":"traceutil/trace.go:171","msg":"trace[2094369291] linearizableReadLoop","detail":"{readStateIndex:26781; appliedIndex:26780; }","duration":"259.685155ms","start":"2026-07-15T05:46:42.841493Z","end":"2026-07-15T05:46:43.101178Z","steps":["trace[2094369291] 'read index received' (duration: 118.731912ms)","trace[2094369291] 'applied index is now lower than readState.Index' (duration: 140.952153ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:43.101322Z","caller":"traceutil/trace.go:171","msg":"trace[1311011186] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"263.767806ms","start":"2026-07-15T05:46:42.837513Z","end":"2026-07-15T05:46:43.101281Z","steps":["trace[1311011186] 'process raft request' (duration: 122.604958ms)","trace[1311011186] 'compare' (duration: 140.695257ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:43.101405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.899251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-07-15T05:46:43.10146Z","caller":"traceutil/trace.go:171","msg":"trace[393849888] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:25926; }","duration":"259.962643ms","start":"2026-07-15T05:46:42.841483Z","end":"2026-07-15T05:46:43.101446Z","steps":["trace[393849888] 'agreement among raft nodes before linearized reading' (duration: 259.792369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.108038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.712676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-15T05:46:43.108313Z","caller":"traceutil/trace.go:171","msg":"trace[1151625342] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25927; }","duration":"211.053796ms","start":"2026-07-15T05:46:42.897236Z","end":"2026-07-15T05:46:43.10829Z","steps":["trace[1151625342] 'agreement among raft nodes before linearized reading' (duration: 210.610366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.108647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.199894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111935"} {"level":"info","ts":"2026-07-15T05:46:43.108717Z","caller":"traceutil/trace.go:171","msg":"trace[356841869] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:25927; }","duration":"266.314767ms","start":"2026-07-15T05:46:42.842388Z","end":"2026-07-15T05:46:43.108703Z","steps":["trace[356841869] 'agreement among raft nodes before linearized reading' (duration: 265.410666ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.108771Z","caller":"traceutil/trace.go:171","msg":"trace[1121633878] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"259.082912ms","start":"2026-07-15T05:46:42.849676Z","end":"2026-07-15T05:46:43.108759Z","steps":["trace[1121633878] 'process raft request' (duration: 257.991555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.108993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.993507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:43.109018Z","caller":"traceutil/trace.go:171","msg":"trace[1724187743] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:25927; }","duration":"235.023518ms","start":"2026-07-15T05:46:42.873986Z","end":"2026-07-15T05:46:43.10901Z","steps":["trace[1724187743] 'agreement among raft nodes before linearized reading' (duration: 234.976556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.109176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.02551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:46:43.109197Z","caller":"traceutil/trace.go:171","msg":"trace[688776596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25927; }","duration":"238.091212ms","start":"2026-07-15T05:46:42.8711Z","end":"2026-07-15T05:46:43.109191Z","steps":["trace[688776596] 'agreement among raft nodes before linearized reading' (duration: 238.02472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.109292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.87222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:46:43.109332Z","caller":"traceutil/trace.go:171","msg":"trace[1145329898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25927; }","duration":"252.930902ms","start":"2026-07-15T05:46:42.856392Z","end":"2026-07-15T05:46:43.109323Z","steps":["trace[1145329898] 'agreement among raft nodes before linearized reading' (duration: 252.83586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.109401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.320755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T05:46:43.109451Z","caller":"traceutil/trace.go:171","msg":"trace[1335106957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25927; }","duration":"242.389036ms","start":"2026-07-15T05:46:42.867049Z","end":"2026-07-15T05:46:43.109438Z","steps":["trace[1335106957] 'agreement among raft nodes before linearized reading' (duration: 242.202501ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.247507Z","caller":"traceutil/trace.go:171","msg":"trace[1547249238] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"134.031935ms","start":"2026-07-15T05:46:43.113449Z","end":"2026-07-15T05:46:43.247481Z","steps":["trace[1547249238] 'process raft request' (duration: 130.854056ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.283453Z","caller":"traceutil/trace.go:171","msg":"trace[356094230] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"169.956357ms","start":"2026-07-15T05:46:43.113472Z","end":"2026-07-15T05:46:43.283428Z","steps":["trace[356094230] 'process raft request' (duration: 169.064036ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.28362Z","caller":"traceutil/trace.go:171","msg":"trace[2078318366] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"169.657659ms","start":"2026-07-15T05:46:43.113953Z","end":"2026-07-15T05:46:43.28361Z","steps":["trace[2078318366] 'process raft request' (duration: 168.713097ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.283815Z","caller":"traceutil/trace.go:171","msg":"trace[1948748824] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"162.115256ms","start":"2026-07-15T05:46:43.121691Z","end":"2026-07-15T05:46:43.283806Z","steps":["trace[1948748824] 'process raft request' (duration: 161.01608ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.284181Z","caller":"traceutil/trace.go:171","msg":"trace[348244956] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"150.840094ms","start":"2026-07-15T05:46:43.13333Z","end":"2026-07-15T05:46:43.28417Z","steps":["trace[348244956] 'process raft request' (duration: 149.431369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.798409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.794287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:46:43.798582Z","caller":"traceutil/trace.go:171","msg":"trace[1501792048] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"257.916953ms","start":"2026-07-15T05:46:43.540611Z","end":"2026-07-15T05:46:43.798528Z","steps":["trace[1501792048] 'process raft request' (duration: 257.871872ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.798742Z","caller":"traceutil/trace.go:171","msg":"trace[850913672] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"331.06015ms","start":"2026-07-15T05:46:43.467674Z","end":"2026-07-15T05:46:43.798734Z","steps":["trace[850913672] 'process raft request' (duration: 131.874443ms)","trace[850913672] 'compare' (duration: 198.537791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:43.798791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:43.467656Z","time spent":"331.116261ms","remote":"127.0.0.1:38952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:46:43.798988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.605024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:43.799014Z","caller":"traceutil/trace.go:171","msg":"trace[1250011804] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25935; }","duration":"287.665505ms","start":"2026-07-15T05:46:43.511341Z","end":"2026-07-15T05:46:43.799006Z","steps":["trace[1250011804] 'agreement among raft nodes before linearized reading' (duration: 287.601354ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.798929Z","caller":"traceutil/trace.go:171","msg":"trace[1341118344] linearizableReadLoop","detail":"{readStateIndex:26790; appliedIndex:26789; }","duration":"269.008303ms","start":"2026-07-15T05:46:43.529897Z","end":"2026-07-15T05:46:43.798905Z","steps":["trace[1341118344] 'read index received' (duration: 69.664912ms)","trace[1341118344] 'applied index is now lower than readState.Index' (duration: 199.205868ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:46:43.799427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.534986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1826"} {"level":"info","ts":"2026-07-15T05:46:43.799518Z","caller":"traceutil/trace.go:171","msg":"trace[1524526883] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:25935; }","duration":"302.67339ms","start":"2026-07-15T05:46:43.496823Z","end":"2026-07-15T05:46:43.799497Z","steps":["trace[1524526883] 'agreement among raft nodes before linearized reading' (duration: 302.27544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.799567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:43.49681Z","time spent":"302.747442ms","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1848,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"info","ts":"2026-07-15T05:46:43.890804Z","caller":"traceutil/trace.go:171","msg":"trace[890565132] transaction","detail":"{read_only:false; response_revision:25937; number_of_response:1; }","duration":"125.500138ms","start":"2026-07-15T05:46:43.765285Z","end":"2026-07-15T05:46:43.890786Z","steps":["trace[890565132] 'process raft request' (duration: 125.374085ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.891245Z","caller":"traceutil/trace.go:171","msg":"trace[1518546178] transaction","detail":"{read_only:false; response_revision:25939; number_of_response:1; }","duration":"118.481187ms","start":"2026-07-15T05:46:43.772753Z","end":"2026-07-15T05:46:43.891235Z","steps":["trace[1518546178] 'process raft request' (duration: 118.268702ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.891224Z","caller":"traceutil/trace.go:171","msg":"trace[1048441108] transaction","detail":"{read_only:false; response_revision:25938; number_of_response:1; }","duration":"118.482917ms","start":"2026-07-15T05:46:43.772711Z","end":"2026-07-15T05:46:43.891194Z","steps":["trace[1048441108] 'process raft request' (duration: 118.045556ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:43.891293Z","caller":"traceutil/trace.go:171","msg":"trace[1649042293] transaction","detail":"{read_only:false; response_revision:25940; number_of_response:1; }","duration":"118.499947ms","start":"2026-07-15T05:46:43.77277Z","end":"2026-07-15T05:46:43.89127Z","steps":["trace[1649042293] 'process raft request' (duration: 118.416805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.891493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.378041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:46:43.891526Z","caller":"traceutil/trace.go:171","msg":"trace[1738137033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25940; }","duration":"360.444153ms","start":"2026-07-15T05:46:43.531073Z","end":"2026-07-15T05:46:43.891517Z","steps":["trace[1738137033] 'agreement among raft nodes before linearized reading' (duration: 360.388481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:43.891546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:46:43.531061Z","time spent":"360.480233ms","remote":"127.0.0.1:38936","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-07-15T05:46:43.891713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.741537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7bdea1ae-eb13-47b9-9b54-c9c10472c7a7\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-15T05:46:43.89174Z","caller":"traceutil/trace.go:171","msg":"trace[1610981329] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7bdea1ae-eb13-47b9-9b54-c9c10472c7a7; range_end:; response_count:1; response_revision:25940; }","duration":"259.793178ms","start":"2026-07-15T05:46:43.631939Z","end":"2026-07-15T05:46:43.891732Z","steps":["trace[1610981329] 'agreement among raft nodes before linearized reading' (duration: 259.709796ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:48.044767Z","caller":"traceutil/trace.go:171","msg":"trace[266850093] transaction","detail":"{read_only:false; response_revision:25980; number_of_response:1; }","duration":"138.277008ms","start":"2026-07-15T05:46:47.906471Z","end":"2026-07-15T05:46:48.044748Z","steps":["trace[266850093] 'process raft request' (duration: 138.139835ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:48.045052Z","caller":"traceutil/trace.go:171","msg":"trace[909147200] linearizableReadLoop","detail":"{readStateIndex:26838; appliedIndex:26834; }","duration":"134.678691ms","start":"2026-07-15T05:46:47.91035Z","end":"2026-07-15T05:46:48.045029Z","steps":["trace[909147200] 'read index received' (duration: 78.957467ms)","trace[909147200] 'applied index is now lower than readState.Index' (duration: 55.720344ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:46:48.045128Z","caller":"traceutil/trace.go:171","msg":"trace[55336460] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"135.339697ms","start":"2026-07-15T05:46:47.909783Z","end":"2026-07-15T05:46:48.045123Z","steps":["trace[55336460] 'process raft request' (duration: 135.139252ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:46:48.045093Z","caller":"traceutil/trace.go:171","msg":"trace[72175342] transaction","detail":"{read_only:false; response_revision:25981; number_of_response:1; }","duration":"137.349296ms","start":"2026-07-15T05:46:47.907734Z","end":"2026-07-15T05:46:48.045083Z","steps":["trace[72175342] 'process raft request' (duration: 136.977667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:46:48.04526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.895405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7bdea1ae-eb13-47b9-9b54-c9c10472c7a7\" ","response":"range_response_count:1 size:3948"} {"level":"info","ts":"2026-07-15T05:46:48.045334Z","caller":"traceutil/trace.go:171","msg":"trace[1217067531] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7bdea1ae-eb13-47b9-9b54-c9c10472c7a7; range_end:; response_count:1; response_revision:25982; }","duration":"134.976517ms","start":"2026-07-15T05:46:47.910339Z","end":"2026-07-15T05:46:48.045316Z","steps":["trace[1217067531] 'agreement among raft nodes before linearized reading' (duration: 134.795763ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:02.846594Z","caller":"traceutil/trace.go:171","msg":"trace[1998411614] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"117.490543ms","start":"2026-07-15T05:47:02.729066Z","end":"2026-07-15T05:47:02.846557Z","steps":["trace[1998411614] 'process raft request' (duration: 117.273238ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:03.268683Z","caller":"traceutil/trace.go:171","msg":"trace[777260930] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"132.468077ms","start":"2026-07-15T05:47:03.136185Z","end":"2026-07-15T05:47:03.268653Z","steps":["trace[777260930] 'process raft request' (duration: 132.294062ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:03.478481Z","caller":"traceutil/trace.go:171","msg":"trace[628617149] linearizableReadLoop","detail":"{readStateIndex:26988; appliedIndex:26986; }","duration":"277.035067ms","start":"2026-07-15T05:47:03.201416Z","end":"2026-07-15T05:47:03.478451Z","steps":["trace[628617149] 'read index received' (duration: 67.057688ms)","trace[628617149] 'applied index is now lower than readState.Index' (duration: 209.976319ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:03.478611Z","caller":"traceutil/trace.go:171","msg":"trace[2017601386] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"291.363225ms","start":"2026-07-15T05:47:03.187205Z","end":"2026-07-15T05:47:03.478568Z","steps":["trace[2017601386] 'process raft request' (duration: 259.362308ms)","trace[2017601386] 'compare' (duration: 31.607148ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:03.478689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.264864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-15T05:47:03.478721Z","caller":"traceutil/trace.go:171","msg":"trace[1444660785] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:26129; }","duration":"277.349496ms","start":"2026-07-15T05:47:03.201363Z","end":"2026-07-15T05:47:03.478712Z","steps":["trace[1444660785] 'agreement among raft nodes before linearized reading' (duration: 277.216512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:03.478722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.918655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:03.478809Z","caller":"traceutil/trace.go:171","msg":"trace[1691572930] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:26129; }","duration":"198.084359ms","start":"2026-07-15T05:47:03.280703Z","end":"2026-07-15T05:47:03.478788Z","steps":["trace[1691572930] 'agreement among raft nodes before linearized reading' (duration: 197.905265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:03.479045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.663627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:47:03.479097Z","caller":"traceutil/trace.go:171","msg":"trace[1648665326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26129; }","duration":"138.74409ms","start":"2026-07-15T05:47:03.340341Z","end":"2026-07-15T05:47:03.479085Z","steps":["trace[1648665326] 'agreement among raft nodes before linearized reading' (duration: 138.641707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:03.753475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.686732ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-15T05:47:03.753959Z","caller":"traceutil/trace.go:171","msg":"trace[1955223581] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"271.883763ms","start":"2026-07-15T05:47:03.482033Z","end":"2026-07-15T05:47:03.753916Z","steps":["trace[1955223581] 'process raft request' (duration: 271.585225ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:03.754115Z","caller":"traceutil/trace.go:171","msg":"trace[1867132312] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"271.76939ms","start":"2026-07-15T05:47:03.482144Z","end":"2026-07-15T05:47:03.753914Z","steps":["trace[1867132312] 'process raft request' (duration: 271.659627ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:03.75425Z","caller":"traceutil/trace.go:171","msg":"trace[484731033] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"158.600472ms","start":"2026-07-15T05:47:03.595619Z","end":"2026-07-15T05:47:03.754219Z","steps":["trace[484731033] 'process raft request' (duration: 158.497679ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:03.754257Z","caller":"traceutil/trace.go:171","msg":"trace[337642054] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"271.278888ms","start":"2026-07-15T05:47:03.482957Z","end":"2026-07-15T05:47:03.754235Z","steps":["trace[337642054] 'process raft request' (duration: 270.911299ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:03.915388Z","caller":"traceutil/trace.go:171","msg":"trace[1732314490] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"159.871232ms","start":"2026-07-15T05:47:03.755482Z","end":"2026-07-15T05:47:03.915354Z","steps":["trace[1732314490] 'process raft request' (duration: 126.518992ms)","trace[1732314490] 'compare' (duration: 32.783157ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:03.915441Z","caller":"traceutil/trace.go:171","msg":"trace[1271227412] linearizableReadLoop","detail":"{readStateIndex:26994; appliedIndex:26993; }","duration":"119.969754ms","start":"2026-07-15T05:47:03.795448Z","end":"2026-07-15T05:47:03.915417Z","steps":["trace[1271227412] 'read index received' (duration: 86.526232ms)","trace[1271227412] 'applied index is now lower than readState.Index' (duration: 33.442172ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:03.915667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.198029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-07-15T05:47:03.915704Z","caller":"traceutil/trace.go:171","msg":"trace[520509087] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:26135; }","duration":"120.24702ms","start":"2026-07-15T05:47:03.795443Z","end":"2026-07-15T05:47:03.91569Z","steps":["trace[520509087] 'agreement among raft nodes before linearized reading' (duration: 120.055816ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:04.320801Z","caller":"traceutil/trace.go:171","msg":"trace[152342693] transaction","detail":"{read_only:false; response_revision:26136; number_of_response:1; }","duration":"268.532001ms","start":"2026-07-15T05:47:04.052213Z","end":"2026-07-15T05:47:04.320745Z","steps":["trace[152342693] 'process raft request' (duration: 268.288485ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:04.428138Z","caller":"traceutil/trace.go:171","msg":"trace[1344995724] linearizableReadLoop","detail":"{readStateIndex:27000; appliedIndex:26995; }","duration":"290.171126ms","start":"2026-07-15T05:47:04.137945Z","end":"2026-07-15T05:47:04.428116Z","steps":["trace[1344995724] 'read index received' (duration: 182.588884ms)","trace[1344995724] 'applied index is now lower than readState.Index' (duration: 107.581372ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:04.428297Z","caller":"traceutil/trace.go:171","msg":"trace[1285527254] transaction","detail":"{read_only:false; response_revision:26137; number_of_response:1; }","duration":"329.4704ms","start":"2026-07-15T05:47:04.098808Z","end":"2026-07-15T05:47:04.428279Z","steps":["trace[1285527254] 'process raft request' (duration: 327.463082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.428532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:04.09874Z","time spent":"329.614504ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:04.428668Z","caller":"traceutil/trace.go:171","msg":"trace[1069860176] transaction","detail":"{read_only:false; response_revision:26138; number_of_response:1; }","duration":"328.59762ms","start":"2026-07-15T05:47:04.100061Z","end":"2026-07-15T05:47:04.428659Z","steps":["trace[1069860176] 'process raft request' (duration: 327.913053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.428757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:04.100037Z","time spent":"328.660812ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:04.428836Z","caller":"traceutil/trace.go:171","msg":"trace[273919351] transaction","detail":"{read_only:false; response_revision:26139; number_of_response:1; }","duration":"327.271457ms","start":"2026-07-15T05:47:04.101555Z","end":"2026-07-15T05:47:04.428827Z","steps":["trace[273919351] 'process raft request' (duration: 326.487398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.42889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:04.101537Z","time spent":"327.325949ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:04.428965Z","caller":"traceutil/trace.go:171","msg":"trace[2048959311] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"325.011962ms","start":"2026-07-15T05:47:04.103944Z","end":"2026-07-15T05:47:04.428956Z","steps":["trace[2048959311] 'process raft request' (duration: 324.138331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.429009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:04.103914Z","time spent":"325.072834ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:04.429116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.163181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:04.429156Z","caller":"traceutil/trace.go:171","msg":"trace[303025319] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26140; }","duration":"291.240303ms","start":"2026-07-15T05:47:04.137906Z","end":"2026-07-15T05:47:04.429146Z","steps":["trace[303025319] 'agreement among raft nodes before linearized reading' (duration: 291.175221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.429194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.575544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T05:47:04.429291Z","caller":"traceutil/trace.go:171","msg":"trace[564686433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26140; }","duration":"266.697227ms","start":"2026-07-15T05:47:04.162557Z","end":"2026-07-15T05:47:04.429254Z","steps":["trace[564686433] 'agreement among raft nodes before linearized reading' (duration: 266.504312ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:04.689077Z","caller":"traceutil/trace.go:171","msg":"trace[2016676083] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"254.382987ms","start":"2026-07-15T05:47:04.434677Z","end":"2026-07-15T05:47:04.68906Z","steps":["trace[2016676083] 'process raft request' (duration: 254.269064ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:04.694378Z","caller":"traceutil/trace.go:171","msg":"trace[104030051] linearizableReadLoop","detail":"{readStateIndex:27002; appliedIndex:27000; }","duration":"255.248748ms","start":"2026-07-15T05:47:04.439101Z","end":"2026-07-15T05:47:04.69435Z","steps":["trace[104030051] 'read index received' (duration: 249.829317ms)","trace[104030051] 'applied index is now lower than readState.Index' (duration: 5.418381ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:04.694755Z","caller":"traceutil/trace.go:171","msg":"trace[528071945] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"259.315867ms","start":"2026-07-15T05:47:04.43542Z","end":"2026-07-15T05:47:04.694735Z","steps":["trace[528071945] 'process raft request' (duration: 258.779034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.695012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.894265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:04.695072Z","caller":"traceutil/trace.go:171","msg":"trace[64774792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26142; }","duration":"255.987497ms","start":"2026-07-15T05:47:04.439071Z","end":"2026-07-15T05:47:04.695058Z","steps":["trace[64774792] 'agreement among raft nodes before linearized reading' (duration: 255.894615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:04.695261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.01661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:04.695303Z","caller":"traceutil/trace.go:171","msg":"trace[1069106838] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:26142; }","duration":"149.091452ms","start":"2026-07-15T05:47:04.546198Z","end":"2026-07-15T05:47:04.695289Z","steps":["trace[1069106838] 'agreement among raft nodes before linearized reading' (duration: 149.02798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.161959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.855718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:12.16208Z","caller":"traceutil/trace.go:171","msg":"trace[1093625715] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26213; }","duration":"131.031533ms","start":"2026-07-15T05:47:12.031032Z","end":"2026-07-15T05:47:12.162064Z","steps":["trace[1093625715] 'count revisions from in-memory index tree' (duration: 130.718545ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:12.620977Z","caller":"traceutil/trace.go:171","msg":"trace[412354470] linearizableReadLoop","detail":"{readStateIndex:27075; appliedIndex:27074; }","duration":"187.264928ms","start":"2026-07-15T05:47:12.433693Z","end":"2026-07-15T05:47:12.620958Z","steps":["trace[412354470] 'read index received' (duration: 187.163075ms)","trace[412354470] 'applied index is now lower than readState.Index' (duration: 101.003µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:12.621073Z","caller":"traceutil/trace.go:171","msg":"trace[673105180] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"277.400927ms","start":"2026-07-15T05:47:12.343649Z","end":"2026-07-15T05:47:12.62105Z","steps":["trace[673105180] 'process raft request' (duration: 277.147691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.621092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.38649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:12.621125Z","caller":"traceutil/trace.go:171","msg":"trace[551224027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26214; }","duration":"187.456982ms","start":"2026-07-15T05:47:12.43366Z","end":"2026-07-15T05:47:12.621117Z","steps":["trace[551224027] 'agreement among raft nodes before linearized reading' (duration: 187.390161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.848107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.4666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:12.848353Z","caller":"traceutil/trace.go:171","msg":"trace[738700103] linearizableReadLoop","detail":"{readStateIndex:27079; appliedIndex:27075; }","duration":"227.30741ms","start":"2026-07-15T05:47:12.621028Z","end":"2026-07-15T05:47:12.848335Z","steps":["trace[738700103] 'read index received' (duration: 84.532713ms)","trace[738700103] 'applied index is now lower than readState.Index' (duration: 142.773657ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:12.848402Z","caller":"traceutil/trace.go:171","msg":"trace[1746769094] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"395.733649ms","start":"2026-07-15T05:47:12.452609Z","end":"2026-07-15T05:47:12.848343Z","steps":["trace[1746769094] 'process raft request' (duration: 252.950252ms)","trace[1746769094] 'compare' (duration: 142.348647ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:12.848414Z","caller":"traceutil/trace.go:171","msg":"trace[634427055] transaction","detail":"{read_only:false; response_revision:26217; number_of_response:1; }","duration":"391.252822ms","start":"2026-07-15T05:47:12.457107Z","end":"2026-07-15T05:47:12.84836Z","steps":["trace[634427055] 'process raft request' (duration: 391.157199ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:12.848492Z","caller":"traceutil/trace.go:171","msg":"trace[1466699043] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"391.223521ms","start":"2026-07-15T05:47:12.457259Z","end":"2026-07-15T05:47:12.848482Z","steps":["trace[1466699043] 'process raft request' (duration: 391.039786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.848534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:12.452591Z","time spent":"395.869063ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:12.84856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:12.457224Z","time spent":"391.301213ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:12.848588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:12.457099Z","time spent":"391.387905ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:12.848753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.87131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:12.848754Z","caller":"traceutil/trace.go:171","msg":"trace[684119782] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"392.494551ms","start":"2026-07-15T05:47:12.456172Z","end":"2026-07-15T05:47:12.848667Z","steps":["trace[684119782] 'process raft request' (duration: 392.03726ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:12.848811Z","caller":"traceutil/trace.go:171","msg":"trace[1647324322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26218; }","duration":"226.982252ms","start":"2026-07-15T05:47:12.621818Z","end":"2026-07-15T05:47:12.8488Z","steps":["trace[1647324322] 'agreement among raft nodes before linearized reading' (duration: 226.810788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.848798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.435673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T05:47:12.848851Z","caller":"traceutil/trace.go:171","msg":"trace[1032514557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26218; }","duration":"115.517905ms","start":"2026-07-15T05:47:12.73332Z","end":"2026-07-15T05:47:12.848838Z","steps":["trace[1032514557] 'agreement among raft nodes before linearized reading' (duration: 115.339241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.848843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.415196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-84f8d4fff7-fv9w6.18c260618b128326\" ","response":"range_response_count:1 size:890"} {"level":"warn","ts":"2026-07-15T05:47:12.848854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.075651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-07-15T05:47:12.848889Z","caller":"traceutil/trace.go:171","msg":"trace[351713760] range","detail":"{range_begin:/registry/events/openstack/magnum-api-84f8d4fff7-fv9w6.18c260618b128326; range_end:; response_count:1; response_revision:26218; }","duration":"139.489257ms","start":"2026-07-15T05:47:12.709387Z","end":"2026-07-15T05:47:12.848876Z","steps":["trace[351713760] 'agreement among raft nodes before linearized reading' (duration: 139.303963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.848798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.058483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-07-15T05:47:12.848904Z","caller":"traceutil/trace.go:171","msg":"trace[1841752318] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26218; }","duration":"147.160483ms","start":"2026-07-15T05:47:12.701725Z","end":"2026-07-15T05:47:12.848885Z","steps":["trace[1841752318] 'agreement among raft nodes before linearized reading' (duration: 147.00983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.848889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:12.456165Z","time spent":"392.654396ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:12.848944Z","caller":"traceutil/trace.go:171","msg":"trace[1001926520] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26218; }","duration":"362.246526ms","start":"2026-07-15T05:47:12.48668Z","end":"2026-07-15T05:47:12.848927Z","steps":["trace[1001926520] 'agreement among raft nodes before linearized reading' (duration: 361.99137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:12.84899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:12.48666Z","time spent":"362.317918ms","remote":"127.0.0.1:55478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3330,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-07-15T05:47:13.146175Z","caller":"traceutil/trace.go:171","msg":"trace[666227506] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"292.226647ms","start":"2026-07-15T05:47:12.85391Z","end":"2026-07-15T05:47:13.146137Z","steps":["trace[666227506] 'process raft request' (duration: 248.766242ms)","trace[666227506] 'compare' (duration: 43.221499ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:13.146187Z","caller":"traceutil/trace.go:171","msg":"trace[1125119725] linearizableReadLoop","detail":"{readStateIndex:27082; appliedIndex:27080; }","duration":"270.126699ms","start":"2026-07-15T05:47:12.876025Z","end":"2026-07-15T05:47:13.146152Z","steps":["trace[1125119725] 'read index received' (duration: 226.737856ms)","trace[1125119725] 'applied index is now lower than readState.Index' (duration: 43.387273ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:13.146336Z","caller":"traceutil/trace.go:171","msg":"trace[112072126] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"270.693763ms","start":"2026-07-15T05:47:12.875616Z","end":"2026-07-15T05:47:13.14631Z","steps":["trace[112072126] 'process raft request' (duration: 270.442707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:13.14649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.442957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18431"} {"level":"info","ts":"2026-07-15T05:47:13.146543Z","caller":"traceutil/trace.go:171","msg":"trace[24485171] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26220; }","duration":"270.5342ms","start":"2026-07-15T05:47:12.875992Z","end":"2026-07-15T05:47:13.146526Z","steps":["trace[24485171] 'agreement among raft nodes before linearized reading' (duration: 270.334154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:13.146556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.707245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T05:47:13.146586Z","caller":"traceutil/trace.go:171","msg":"trace[2052812257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26220; }","duration":"263.772176ms","start":"2026-07-15T05:47:12.882805Z","end":"2026-07-15T05:47:13.146578Z","steps":["trace[2052812257] 'agreement among raft nodes before linearized reading' (duration: 263.624592ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:13.30215Z","caller":"traceutil/trace.go:171","msg":"trace[498104243] linearizableReadLoop","detail":"{readStateIndex:27083; appliedIndex:27082; }","duration":"100.03766ms","start":"2026-07-15T05:47:13.202078Z","end":"2026-07-15T05:47:13.302115Z","steps":["trace[498104243] 'read index received' (duration: 93.361998ms)","trace[498104243] 'applied index is now lower than readState.Index' (duration: 6.671512ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:13.302198Z","caller":"traceutil/trace.go:171","msg":"trace[177181029] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"151.97688ms","start":"2026-07-15T05:47:13.1502Z","end":"2026-07-15T05:47:13.302177Z","steps":["trace[177181029] 'process raft request' (duration: 145.202906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:13.30237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.256785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:47:13.302437Z","caller":"traceutil/trace.go:171","msg":"trace[1414582771] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26222; }","duration":"100.348077ms","start":"2026-07-15T05:47:13.202072Z","end":"2026-07-15T05:47:13.30242Z","steps":["trace[1414582771] 'agreement among raft nodes before linearized reading' (duration: 100.146942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:13.302419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.234274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-15T05:47:13.302501Z","caller":"traceutil/trace.go:171","msg":"trace[1740883790] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:26222; }","duration":"100.345867ms","start":"2026-07-15T05:47:13.202132Z","end":"2026-07-15T05:47:13.302477Z","steps":["trace[1740883790] 'agreement among raft nodes before linearized reading' (duration: 100.163203ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:18.834213Z","caller":"traceutil/trace.go:171","msg":"trace[1333714508] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"194.78314ms","start":"2026-07-15T05:47:18.6394Z","end":"2026-07-15T05:47:18.834183Z","steps":["trace[1333714508] 'process raft request' (duration: 194.614996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.077355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.743576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:19.077472Z","caller":"traceutil/trace.go:171","msg":"trace[945610726] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"203.40654ms","start":"2026-07-15T05:47:18.874042Z","end":"2026-07-15T05:47:19.077449Z","steps":["trace[945610726] 'process raft request' (duration: 72.479311ms)","trace[945610726] 'compare' (duration: 130.359155ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:19.080818Z","caller":"traceutil/trace.go:171","msg":"trace[1290855853] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"198.247374ms","start":"2026-07-15T05:47:18.882546Z","end":"2026-07-15T05:47:19.080793Z","steps":["trace[1290855853] 'process raft request' (duration: 198.07134ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:19.080837Z","caller":"traceutil/trace.go:171","msg":"trace[1857327179] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"197.608349ms","start":"2026-07-15T05:47:18.8832Z","end":"2026-07-15T05:47:19.080808Z","steps":["trace[1857327179] 'process raft request' (duration: 197.524027ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:19.080974Z","caller":"traceutil/trace.go:171","msg":"trace[1967533478] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"196.874621ms","start":"2026-07-15T05:47:18.884073Z","end":"2026-07-15T05:47:19.080947Z","steps":["trace[1967533478] 'process raft request' (duration: 196.698796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.482551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.828529ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-15T05:47:19.482644Z","caller":"traceutil/trace.go:171","msg":"trace[1980022866] linearizableReadLoop","detail":"{readStateIndex:27136; appliedIndex:27135; }","duration":"322.319907ms","start":"2026-07-15T05:47:19.160307Z","end":"2026-07-15T05:47:19.482627Z","steps":["trace[1980022866] 'read index received' (duration: 55.421216ms)","trace[1980022866] 'applied index is now lower than readState.Index' (duration: 266.897411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:19.482788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.46843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:19.482831Z","caller":"traceutil/trace.go:171","msg":"trace[435162323] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:26272; }","duration":"322.549272ms","start":"2026-07-15T05:47:19.160271Z","end":"2026-07-15T05:47:19.48282Z","steps":["trace[435162323] 'agreement among raft nodes before linearized reading' (duration: 322.4624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.48287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:19.160256Z","time spent":"322.601853ms","remote":"127.0.0.1:58370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:47:19.48292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.227919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:19.483224Z","caller":"traceutil/trace.go:171","msg":"trace[1442187449] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26272; }","duration":"281.566616ms","start":"2026-07-15T05:47:19.201634Z","end":"2026-07-15T05:47:19.4832Z","steps":["trace[1442187449] 'agreement among raft nodes before linearized reading' (duration: 281.215148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.48294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.677169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T05:47:19.483323Z","caller":"traceutil/trace.go:171","msg":"trace[692144374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26272; }","duration":"312.10182ms","start":"2026-07-15T05:47:19.171197Z","end":"2026-07-15T05:47:19.483299Z","steps":["trace[692144374] 'agreement among raft nodes before linearized reading' (duration: 311.629218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.482985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.45447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T05:47:19.483431Z","caller":"traceutil/trace.go:171","msg":"trace[1066960988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26272; }","duration":"161.924902ms","start":"2026-07-15T05:47:19.32148Z","end":"2026-07-15T05:47:19.483405Z","steps":["trace[1066960988] 'agreement among raft nodes before linearized reading' (duration: 161.365108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:19.483371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:19.171179Z","time spent":"312.179991ms","remote":"127.0.0.1:39158","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-07-15T05:47:19.676203Z","caller":"traceutil/trace.go:171","msg":"trace[624890013] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"188.907217ms","start":"2026-07-15T05:47:19.487275Z","end":"2026-07-15T05:47:19.676182Z","steps":["trace[624890013] 'process raft request' (duration: 188.754854ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:20.04018Z","caller":"traceutil/trace.go:171","msg":"trace[1484011007] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"501.986571ms","start":"2026-07-15T05:47:19.538162Z","end":"2026-07-15T05:47:20.040148Z","steps":["trace[1484011007] 'process raft request' (duration: 501.878688ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:20.040221Z","caller":"traceutil/trace.go:171","msg":"trace[999534650] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"552.915276ms","start":"2026-07-15T05:47:19.487266Z","end":"2026-07-15T05:47:20.040182Z","steps":["trace[999534650] 'process raft request' (duration: 496.316682ms)","trace[999534650] 'compare' (duration: 56.202865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:20.040641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:19.487248Z","time spent":"553.309026ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:20.040956Z","caller":"traceutil/trace.go:171","msg":"trace[1131617613] linearizableReadLoop","detail":"{readStateIndex:27140; appliedIndex:27137; }","duration":"219.570372ms","start":"2026-07-15T05:47:19.821356Z","end":"2026-07-15T05:47:20.040926Z","steps":["trace[1131617613] 'read index received' (duration: 162.310141ms)","trace[1131617613] 'applied index is now lower than readState.Index' (duration: 57.258941ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:20.040428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:19.538141Z","time spent":"502.173575ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:20.041371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.005283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T05:47:20.041461Z","caller":"traceutil/trace.go:171","msg":"trace[1167924709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26276; }","duration":"220.149127ms","start":"2026-07-15T05:47:19.821289Z","end":"2026-07-15T05:47:20.041438Z","steps":["trace[1167924709] 'agreement among raft nodes before linearized reading' (duration: 219.915171ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:20.041469Z","caller":"traceutil/trace.go:171","msg":"trace[1289453148] transaction","detail":"{read_only:false; response_revision:26276; number_of_response:1; }","duration":"231.960884ms","start":"2026-07-15T05:47:19.809485Z","end":"2026-07-15T05:47:20.041446Z","steps":["trace[1289453148] 'process raft request' (duration: 231.346369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:20.042492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.974451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:47:20.042581Z","caller":"traceutil/trace.go:171","msg":"trace[404703536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26277; }","duration":"220.066613ms","start":"2026-07-15T05:47:19.822496Z","end":"2026-07-15T05:47:20.042563Z","steps":["trace[404703536] 'agreement among raft nodes before linearized reading' (duration: 219.839048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:20.043092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.637854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:47:20.043167Z","caller":"traceutil/trace.go:171","msg":"trace[1245547651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26277; }","duration":"219.758347ms","start":"2026-07-15T05:47:19.823392Z","end":"2026-07-15T05:47:20.043151Z","steps":["trace[1245547651] 'agreement among raft nodes before linearized reading' (duration: 219.559132ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:21.192766Z","caller":"traceutil/trace.go:171","msg":"trace[1729086225] transaction","detail":"{read_only:false; response_revision:26283; number_of_response:1; }","duration":"100.243735ms","start":"2026-07-15T05:47:21.092474Z","end":"2026-07-15T05:47:21.192717Z","steps":["trace[1729086225] 'process raft request' (duration: 99.847865ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:21.192894Z","caller":"traceutil/trace.go:171","msg":"trace[1524854155] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"100.347877ms","start":"2026-07-15T05:47:21.09254Z","end":"2026-07-15T05:47:21.192888Z","steps":["trace[1524854155] 'process raft request' (duration: 99.892526ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:22.886254Z","caller":"traceutil/trace.go:171","msg":"trace[594670581] transaction","detail":"{read_only:false; response_revision:26299; number_of_response:1; }","duration":"121.390817ms","start":"2026-07-15T05:47:22.764823Z","end":"2026-07-15T05:47:22.886214Z","steps":["trace[594670581] 'process raft request' (duration: 121.222893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:23.103996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.688836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:23.104076Z","caller":"traceutil/trace.go:171","msg":"trace[2030255286] transaction","detail":"{read_only:false; response_revision:26300; number_of_response:1; }","duration":"145.937194ms","start":"2026-07-15T05:47:22.958123Z","end":"2026-07-15T05:47:23.104061Z","steps":["trace[2030255286] 'process raft request' (duration: 33.115445ms)","trace[2030255286] 'compare' (duration: 112.471461ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:23.490715Z","caller":"traceutil/trace.go:171","msg":"trace[1708819486] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"312.880578ms","start":"2026-07-15T05:47:23.177801Z","end":"2026-07-15T05:47:23.490681Z","steps":["trace[1708819486] 'process raft request' (duration: 234.244559ms)","trace[1708819486] 'compare' (duration: 78.379623ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:23.49092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:23.177781Z","time spent":"313.040962ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:23.491019Z","caller":"traceutil/trace.go:171","msg":"trace[1228318180] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"293.766734ms","start":"2026-07-15T05:47:23.197228Z","end":"2026-07-15T05:47:23.490994Z","steps":["trace[1228318180] 'process raft request' (duration: 293.376404ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:23.491236Z","caller":"traceutil/trace.go:171","msg":"trace[1194095491] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"292.639386ms","start":"2026-07-15T05:47:23.19856Z","end":"2026-07-15T05:47:23.491199Z","steps":["trace[1194095491] 'process raft request' (duration: 292.293467ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:23.491296Z","caller":"traceutil/trace.go:171","msg":"trace[1156295901] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"292.461471ms","start":"2026-07-15T05:47:23.198811Z","end":"2026-07-15T05:47:23.491273Z","steps":["trace[1156295901] 'process raft request' (duration: 292.129103ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:23.491436Z","caller":"traceutil/trace.go:171","msg":"trace[2141979197] linearizableReadLoop","detail":"{readStateIndex:27168; appliedIndex:27164; }","duration":"288.874835ms","start":"2026-07-15T05:47:23.202546Z","end":"2026-07-15T05:47:23.491421Z","steps":["trace[2141979197] 'read index received' (duration: 209.763474ms)","trace[2141979197] 'applied index is now lower than readState.Index' (duration: 79.110181ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:23.491453Z","caller":"traceutil/trace.go:171","msg":"trace[33547285] transaction","detail":"{read_only:false; response_revision:26305; number_of_response:1; }","duration":"177.149462ms","start":"2026-07-15T05:47:23.314279Z","end":"2026-07-15T05:47:23.491429Z","steps":["trace[33547285] 'process raft request' (duration: 176.704001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:23.491602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.045139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-15T05:47:23.491655Z","caller":"traceutil/trace.go:171","msg":"trace[1909654901] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:26305; }","duration":"289.119911ms","start":"2026-07-15T05:47:23.20251Z","end":"2026-07-15T05:47:23.49163Z","steps":["trace[1909654901] 'agreement among raft nodes before linearized reading' (duration: 288.994988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:23.890698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.309775ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:23.890781Z","caller":"traceutil/trace.go:171","msg":"trace[581727] linearizableReadLoop","detail":"{readStateIndex:27172; appliedIndex:27171; }","duration":"151.13197ms","start":"2026-07-15T05:47:23.739637Z","end":"2026-07-15T05:47:23.890769Z","steps":["trace[581727] 'read index received' (duration: 34.678232ms)","trace[581727] 'applied index is now lower than readState.Index' (duration: 116.452748ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:23.890839Z","caller":"traceutil/trace.go:171","msg":"trace[782703253] transaction","detail":"{read_only:false; response_revision:26307; number_of_response:1; }","duration":"187.977205ms","start":"2026-07-15T05:47:23.702855Z","end":"2026-07-15T05:47:23.890832Z","steps":["trace[782703253] 'process raft request' (duration: 71.463455ms)","trace[782703253] 'compare' (duration: 116.144421ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:23.890972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.319624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:23.891048Z","caller":"traceutil/trace.go:171","msg":"trace[184322453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26307; }","duration":"151.425197ms","start":"2026-07-15T05:47:23.739603Z","end":"2026-07-15T05:47:23.891028Z","steps":["trace[184322453] 'agreement among raft nodes before linearized reading' (duration: 151.300424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:23.891039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.827858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:23.891176Z","caller":"traceutil/trace.go:171","msg":"trace[1786861221] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26307; }","duration":"129.032913ms","start":"2026-07-15T05:47:23.762123Z","end":"2026-07-15T05:47:23.891156Z","steps":["trace[1786861221] 'agreement among raft nodes before linearized reading' (duration: 128.811628ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:24.168615Z","caller":"traceutil/trace.go:171","msg":"trace[468444385] transaction","detail":"{read_only:false; response_revision:26308; number_of_response:1; }","duration":"116.280854ms","start":"2026-07-15T05:47:24.052307Z","end":"2026-07-15T05:47:24.168588Z","steps":["trace[468444385] 'process raft request' (duration: 116.12618ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:24.177459Z","caller":"traceutil/trace.go:171","msg":"trace[663986355] transaction","detail":"{read_only:false; response_revision:26309; number_of_response:1; }","duration":"123.5847ms","start":"2026-07-15T05:47:24.053858Z","end":"2026-07-15T05:47:24.177442Z","steps":["trace[663986355] 'process raft request' (duration: 123.462657ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:24.17758Z","caller":"traceutil/trace.go:171","msg":"trace[417997560] linearizableReadLoop","detail":"{readStateIndex:27175; appliedIndex:27172; }","duration":"120.933406ms","start":"2026-07-15T05:47:24.056615Z","end":"2026-07-15T05:47:24.177549Z","steps":["trace[417997560] 'read index received' (duration: 111.774684ms)","trace[417997560] 'applied index is now lower than readState.Index' (duration: 9.156432ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:24.177645Z","caller":"traceutil/trace.go:171","msg":"trace[396318604] transaction","detail":"{read_only:false; response_revision:26310; number_of_response:1; }","duration":"123.152681ms","start":"2026-07-15T05:47:24.054441Z","end":"2026-07-15T05:47:24.177594Z","steps":["trace[396318604] 'process raft request' (duration: 122.962706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:24.177768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.138012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-15T05:47:24.177809Z","caller":"traceutil/trace.go:171","msg":"trace[2134115070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26310; }","duration":"121.217313ms","start":"2026-07-15T05:47:24.056582Z","end":"2026-07-15T05:47:24.177799Z","steps":["trace[2134115070] 'agreement among raft nodes before linearized reading' (duration: 121.07705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:24.177856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.406364ms","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-07-15T05:47:24.177901Z","caller":"traceutil/trace.go:171","msg":"trace[776718018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26310; }","duration":"120.490236ms","start":"2026-07-15T05:47:24.057399Z","end":"2026-07-15T05:47:24.177889Z","steps":["trace[776718018] 'agreement among raft nodes before linearized reading' (duration: 120.378003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:24.177948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.048029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T05:47:24.177998Z","caller":"traceutil/trace.go:171","msg":"trace[2020460860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26310; }","duration":"114.12618ms","start":"2026-07-15T05:47:24.063857Z","end":"2026-07-15T05:47:24.177983Z","steps":["trace[2020460860] 'agreement among raft nodes before linearized reading' (duration: 113.928996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:24.17795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.511641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-15T05:47:24.178184Z","caller":"traceutil/trace.go:171","msg":"trace[1282130916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26310; }","duration":"114.777398ms","start":"2026-07-15T05:47:24.06339Z","end":"2026-07-15T05:47:24.178168Z","steps":["trace[1282130916] 'agreement among raft nodes before linearized reading' (duration: 114.420549ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:24.368788Z","caller":"traceutil/trace.go:171","msg":"trace[1537981395] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"187.174454ms","start":"2026-07-15T05:47:24.181595Z","end":"2026-07-15T05:47:24.36877Z","steps":["trace[1537981395] 'process raft request' (duration: 187.141774ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:24.369064Z","caller":"traceutil/trace.go:171","msg":"trace[1726316879] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"187.670227ms","start":"2026-07-15T05:47:24.181385Z","end":"2026-07-15T05:47:24.369055Z","steps":["trace[1726316879] 'process raft request' (duration: 187.213825ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:24.369163Z","caller":"traceutil/trace.go:171","msg":"trace[470107982] transaction","detail":"{read_only:false; response_revision:26313; number_of_response:1; }","duration":"187.563715ms","start":"2026-07-15T05:47:24.181573Z","end":"2026-07-15T05:47:24.369137Z","steps":["trace[470107982] 'process raft request' (duration: 187.131594ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:26.549308Z","caller":"traceutil/trace.go:171","msg":"trace[210923579] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"172.816547ms","start":"2026-07-15T05:47:26.376475Z","end":"2026-07-15T05:47:26.549291Z","steps":["trace[210923579] 'process raft request' (duration: 172.700584ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:26.550735Z","caller":"traceutil/trace.go:171","msg":"trace[1425748337] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"173.391651ms","start":"2026-07-15T05:47:26.377323Z","end":"2026-07-15T05:47:26.550715Z","steps":["trace[1425748337] 'process raft request' (duration: 173.35438ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:26.551014Z","caller":"traceutil/trace.go:171","msg":"trace[124288683] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"174.273012ms","start":"2026-07-15T05:47:26.376734Z","end":"2026-07-15T05:47:26.551007Z","steps":["trace[124288683] 'process raft request' (duration: 173.848312ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:46.343155Z","caller":"traceutil/trace.go:171","msg":"trace[215419231] transaction","detail":"{read_only:false; response_revision:26480; number_of_response:1; }","duration":"100.819748ms","start":"2026-07-15T05:47:46.242322Z","end":"2026-07-15T05:47:46.343141Z","steps":["trace[215419231] 'process raft request' (duration: 100.708636ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:46.343375Z","caller":"traceutil/trace.go:171","msg":"trace[1409004085] transaction","detail":"{read_only:false; response_revision:26481; number_of_response:1; }","duration":"100.534051ms","start":"2026-07-15T05:47:46.242806Z","end":"2026-07-15T05:47:46.34334Z","steps":["trace[1409004085] 'process raft request' (duration: 100.312686ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:46.479367Z","caller":"traceutil/trace.go:171","msg":"trace[1608401524] transaction","detail":"{read_only:false; response_revision:26482; number_of_response:1; }","duration":"130.663842ms","start":"2026-07-15T05:47:46.348666Z","end":"2026-07-15T05:47:46.47933Z","steps":["trace[1608401524] 'process raft request' (duration: 65.92768ms)","trace[1608401524] 'compare' (duration: 64.360903ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:46.894483Z","caller":"traceutil/trace.go:171","msg":"trace[1328760997] linearizableReadLoop","detail":"{readStateIndex:27352; appliedIndex:27351; }","duration":"246.695661ms","start":"2026-07-15T05:47:46.647755Z","end":"2026-07-15T05:47:46.894451Z","steps":["trace[1328760997] 'read index received' (duration: 246.446195ms)","trace[1328760997] 'applied index is now lower than readState.Index' (duration: 248.246µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:46.894531Z","caller":"traceutil/trace.go:171","msg":"trace[1026302144] transaction","detail":"{read_only:false; response_revision:26483; number_of_response:1; }","duration":"247.221254ms","start":"2026-07-15T05:47:46.647268Z","end":"2026-07-15T05:47:46.894489Z","steps":["trace[1026302144] 'process raft request' (duration: 246.971848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:46.894812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.03993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-15T05:47:46.894894Z","caller":"traceutil/trace.go:171","msg":"trace[1945530886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26483; }","duration":"247.161763ms","start":"2026-07-15T05:47:46.647713Z","end":"2026-07-15T05:47:46.894875Z","steps":["trace[1945530886] 'agreement among raft nodes before linearized reading' (duration: 246.878675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:46.894894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.855576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-15T05:47:46.894971Z","caller":"traceutil/trace.go:171","msg":"trace[943771908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26483; }","duration":"246.986299ms","start":"2026-07-15T05:47:46.647967Z","end":"2026-07-15T05:47:46.894953Z","steps":["trace[943771908] 'agreement among raft nodes before linearized reading' (duration: 246.65881ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:47.065215Z","caller":"traceutil/trace.go:171","msg":"trace[453963069] transaction","detail":"{read_only:false; response_revision:26484; number_of_response:1; }","duration":"165.869488ms","start":"2026-07-15T05:47:46.899308Z","end":"2026-07-15T05:47:47.065178Z","steps":["trace[453963069] 'process raft request' (duration: 105.639505ms)","trace[453963069] 'compare' (duration: 59.896635ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:47.100428Z","caller":"traceutil/trace.go:171","msg":"trace[579359355] transaction","detail":"{read_only:false; response_revision:26485; number_of_response:1; }","duration":"200.831358ms","start":"2026-07-15T05:47:46.899525Z","end":"2026-07-15T05:47:47.100356Z","steps":["trace[579359355] 'process raft request' (duration: 200.696954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.100853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.274953ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:48.100978Z","caller":"traceutil/trace.go:171","msg":"trace[341435054] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26487; }","duration":"125.412836ms","start":"2026-07-15T05:47:47.975548Z","end":"2026-07-15T05:47:48.10096Z","steps":["trace[341435054] 'range keys from in-memory index tree' (duration: 125.257663ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:48.117647Z","caller":"traceutil/trace.go:171","msg":"trace[919402441] transaction","detail":"{read_only:false; response_revision:26490; number_of_response:1; }","duration":"506.387808ms","start":"2026-07-15T05:47:47.611239Z","end":"2026-07-15T05:47:48.117627Z","steps":["trace[919402441] 'process raft request' (duration: 506.348627ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:48.117711Z","caller":"traceutil/trace.go:171","msg":"trace[1364851064] transaction","detail":"{read_only:false; response_revision:26488; number_of_response:1; }","duration":"510.905306ms","start":"2026-07-15T05:47:47.606776Z","end":"2026-07-15T05:47:48.117681Z","steps":["trace[1364851064] 'process raft request' (duration: 510.64345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.117793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:47.611229Z","time spent":"506.48667ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:48.117748Z","caller":"traceutil/trace.go:171","msg":"trace[936379361] transaction","detail":"{read_only:false; response_revision:26489; number_of_response:1; }","duration":"508.327594ms","start":"2026-07-15T05:47:47.609371Z","end":"2026-07-15T05:47:48.117699Z","steps":["trace[936379361] 'process raft request' (duration: 508.18561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.117822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:47.60675Z","time spent":"511.014699ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T05:47:48.118204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:47.60931Z","time spent":"508.593641ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:48.417246Z","caller":"traceutil/trace.go:171","msg":"trace[927928757] transaction","detail":"{read_only:false; response_revision:26491; number_of_response:1; }","duration":"608.415004ms","start":"2026-07-15T05:47:47.808804Z","end":"2026-07-15T05:47:48.417219Z","steps":["trace[927928757] 'process raft request' (duration: 538.892516ms)","trace[927928757] 'compare' (duration: 69.269052ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:48.417366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:47.808786Z","time spent":"608.514607ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:48.417344Z","caller":"traceutil/trace.go:171","msg":"trace[87507637] linearizableReadLoop","detail":"{readStateIndex:27360; appliedIndex:27356; }","duration":"414.290961ms","start":"2026-07-15T05:47:48.003026Z","end":"2026-07-15T05:47:48.417317Z","steps":["trace[87507637] 'read index received' (duration: 114.48058ms)","trace[87507637] 'applied index is now lower than readState.Index' (duration: 299.801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:48.417452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.404823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:48.417508Z","caller":"traceutil/trace.go:171","msg":"trace[2111491389] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26491; }","duration":"414.497726ms","start":"2026-07-15T05:47:48.002993Z","end":"2026-07-15T05:47:48.417491Z","steps":["trace[2111491389] 'agreement among raft nodes before linearized reading' (duration: 414.398083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.417532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:48.002978Z","time spent":"414.548557ms","remote":"127.0.0.1:46732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-07-15T05:47:48.417657Z","caller":"traceutil/trace.go:171","msg":"trace[481786590] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"131.616537ms","start":"2026-07-15T05:47:48.28603Z","end":"2026-07-15T05:47:48.417647Z","steps":["trace[481786590] 'process raft request' (duration: 131.060723ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:48.651645Z","caller":"traceutil/trace.go:171","msg":"trace[628929058] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"302.923196ms","start":"2026-07-15T05:47:48.348697Z","end":"2026-07-15T05:47:48.65162Z","steps":["trace[628929058] 'process raft request' (duration: 246.346731ms)","trace[628929058] 'compare' (duration: 56.273437ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:48.651782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:48.34867Z","time spent":"303.048109ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:48.697219Z","caller":"traceutil/trace.go:171","msg":"trace[1201592903] transaction","detail":"{read_only:false; response_revision:26494; number_of_response:1; }","duration":"334.871362ms","start":"2026-07-15T05:47:48.362319Z","end":"2026-07-15T05:47:48.697191Z","steps":["trace[1201592903] 'process raft request' (duration: 334.642197ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:48.697261Z","caller":"traceutil/trace.go:171","msg":"trace[1473218791] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"176.054986ms","start":"2026-07-15T05:47:48.521153Z","end":"2026-07-15T05:47:48.697208Z","steps":["trace[1473218791] 'process raft request' (duration: 175.943483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.697337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:48.362293Z","time spent":"334.983945ms","remote":"127.0.0.1:39158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T05:47:48.716087Z","caller":"traceutil/trace.go:171","msg":"trace[1962112470] linearizableReadLoop","detail":"{readStateIndex:27363; appliedIndex:27361; }","duration":"298.659543ms","start":"2026-07-15T05:47:48.417403Z","end":"2026-07-15T05:47:48.716063Z","steps":["trace[1962112470] 'read index received' (duration: 177.666794ms)","trace[1962112470] 'applied index is now lower than readState.Index' (duration: 120.991359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T05:47:48.716453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.4243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:47:48.716494Z","caller":"traceutil/trace.go:171","msg":"trace[1717884888] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:26495; }","duration":"504.513192ms","start":"2026-07-15T05:47:48.211967Z","end":"2026-07-15T05:47:48.716481Z","steps":["trace[1717884888] 'agreement among raft nodes before linearized reading' (duration: 504.395449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.716529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:48.211944Z","time spent":"504.575683ms","remote":"127.0.0.1:39462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":10,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-07-15T05:47:48.716783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.30372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T05:47:48.716814Z","caller":"traceutil/trace.go:171","msg":"trace[386752729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26495; }","duration":"640.388082ms","start":"2026-07-15T05:47:48.076415Z","end":"2026-07-15T05:47:48.716803Z","steps":["trace[386752729] 'agreement among raft nodes before linearized reading' (duration: 640.270809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.716841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T05:47:48.076361Z","time spent":"640.474124ms","remote":"127.0.0.1:39158","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-07-15T05:47:48.716854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.988992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T05:47:48.716928Z","caller":"traceutil/trace.go:171","msg":"trace[170732956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26495; }","duration":"283.096515ms","start":"2026-07-15T05:47:48.433808Z","end":"2026-07-15T05:47:48.716905Z","steps":["trace[170732956] 'agreement among raft nodes before linearized reading' (duration: 282.983592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.717484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.448039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-15T05:47:48.71753Z","caller":"traceutil/trace.go:171","msg":"trace[1985977795] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:26495; }","duration":"114.534712ms","start":"2026-07-15T05:47:48.602983Z","end":"2026-07-15T05:47:48.717518Z","steps":["trace[1985977795] 'agreement among raft nodes before linearized reading' (duration: 114.321837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.71768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.888169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:47:48.717809Z","caller":"traceutil/trace.go:171","msg":"trace[1053191643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26495; }","duration":"234.084994ms","start":"2026-07-15T05:47:48.483694Z","end":"2026-07-15T05:47:48.717779Z","steps":["trace[1053191643] 'agreement among raft nodes before linearized reading' (duration: 233.653724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:48.874167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.430512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:48.874356Z","caller":"traceutil/trace.go:171","msg":"trace[759324246] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"152.556975ms","start":"2026-07-15T05:47:48.721778Z","end":"2026-07-15T05:47:48.874335Z","steps":["trace[759324246] 'process raft request' (duration: 152.490793ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:48.874513Z","caller":"traceutil/trace.go:171","msg":"trace[334885138] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"153.007235ms","start":"2026-07-15T05:47:48.721472Z","end":"2026-07-15T05:47:48.874479Z","steps":["trace[334885138] 'process raft request' (duration: 51.173153ms)","trace[334885138] 'compare' (duration: 101.278219ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:52.923626Z","caller":"traceutil/trace.go:171","msg":"trace[2135569466] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"259.892991ms","start":"2026-07-15T05:47:52.663708Z","end":"2026-07-15T05:47:52.923601Z","steps":["trace[2135569466] 'process raft request' (duration: 259.729088ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:53.008657Z","caller":"traceutil/trace.go:171","msg":"trace[1703847336] linearizableReadLoop","detail":"{readStateIndex:27397; appliedIndex:27395; }","duration":"122.097236ms","start":"2026-07-15T05:47:52.886498Z","end":"2026-07-15T05:47:53.008595Z","steps":["trace[1703847336] 'read index received' (duration: 36.925748ms)","trace[1703847336] 'applied index is now lower than readState.Index' (duration: 85.170248ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:53.008707Z","caller":"traceutil/trace.go:171","msg":"trace[1258768537] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"297.150667ms","start":"2026-07-15T05:47:52.711511Z","end":"2026-07-15T05:47:53.008662Z","steps":["trace[1258768537] 'process raft request' (duration: 296.190464ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:53.008818Z","caller":"traceutil/trace.go:171","msg":"trace[1343930900] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"103.970915ms","start":"2026-07-15T05:47:52.904819Z","end":"2026-07-15T05:47:53.008789Z","steps":["trace[1343930900] 'process raft request' (duration: 103.682168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:53.008933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.738567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T05:47:53.008975Z","caller":"traceutil/trace.go:171","msg":"trace[1315703986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26528; }","duration":"121.793538ms","start":"2026-07-15T05:47:52.88717Z","end":"2026-07-15T05:47:53.008963Z","steps":["trace[1315703986] 'agreement among raft nodes before linearized reading' (duration: 121.659265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:53.00897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.457104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T05:47:53.009034Z","caller":"traceutil/trace.go:171","msg":"trace[1369969963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26528; }","duration":"122.575397ms","start":"2026-07-15T05:47:52.886442Z","end":"2026-07-15T05:47:53.009017Z","steps":["trace[1369969963] 'agreement among raft nodes before linearized reading' (duration: 122.324971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:53.28743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.850606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T05:47:53.287573Z","caller":"traceutil/trace.go:171","msg":"trace[1391081877] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"274.308021ms","start":"2026-07-15T05:47:53.013243Z","end":"2026-07-15T05:47:53.287551Z","steps":["trace[1391081877] 'process raft request' (duration: 127.24585ms)","trace[1391081877] 'compare' (duration: 146.520087ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:53.298727Z","caller":"traceutil/trace.go:171","msg":"trace[466926336] linearizableReadLoop","detail":"{readStateIndex:27401; appliedIndex:27398; }","duration":"211.699312ms","start":"2026-07-15T05:47:53.08701Z","end":"2026-07-15T05:47:53.298709Z","steps":["trace[466926336] 'read index received' (duration: 53.714874ms)","trace[466926336] 'applied index is now lower than readState.Index' (duration: 157.983348ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T05:47:53.298777Z","caller":"traceutil/trace.go:171","msg":"trace[407378902] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"285.215276ms","start":"2026-07-15T05:47:53.013528Z","end":"2026-07-15T05:47:53.298743Z","steps":["trace[407378902] 'process raft request' (duration: 284.98331ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:53.298834Z","caller":"traceutil/trace.go:171","msg":"trace[281585837] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"278.041643ms","start":"2026-07-15T05:47:53.020778Z","end":"2026-07-15T05:47:53.298819Z","steps":["trace[281585837] 'process raft request' (duration: 277.888059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:53.299017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.982408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-15T05:47:53.299093Z","caller":"traceutil/trace.go:171","msg":"trace[389922986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26531; }","duration":"212.104441ms","start":"2026-07-15T05:47:53.086971Z","end":"2026-07-15T05:47:53.299076Z","steps":["trace[389922986] 'agreement among raft nodes before linearized reading' (duration: 211.861665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:53.299858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.124116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T05:47:53.299925Z","caller":"traceutil/trace.go:171","msg":"trace[1510845487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26532; }","duration":"178.225208ms","start":"2026-07-15T05:47:53.121686Z","end":"2026-07-15T05:47:53.299912Z","steps":["trace[1510845487] 'agreement among raft nodes before linearized reading' (duration: 178.079815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T05:47:53.300127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.915505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T05:47:53.30017Z","caller":"traceutil/trace.go:171","msg":"trace[394748883] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26532; }","duration":"115.997976ms","start":"2026-07-15T05:47:53.18416Z","end":"2026-07-15T05:47:53.300158Z","steps":["trace[394748883] 'agreement among raft nodes before linearized reading' (duration: 115.927554ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:55.139547Z","caller":"traceutil/trace.go:171","msg":"trace[1160729436] transaction","detail":"{read_only:false; response_revision:26546; number_of_response:1; }","duration":"126.614846ms","start":"2026-07-15T05:47:55.012907Z","end":"2026-07-15T05:47:55.139521Z","steps":["trace[1160729436] 'process raft request' (duration: 120.667142ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:47:58.849557Z","caller":"traceutil/trace.go:171","msg":"trace[2147215914] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"135.47443ms","start":"2026-07-15T05:47:58.714055Z","end":"2026-07-15T05:47:58.849529Z","steps":["trace[2147215914] 'process raft request' (duration: 135.226504ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:49:25.623677Z","caller":"traceutil/trace.go:171","msg":"trace[1972030167] transaction","detail":"{read_only:false; response_revision:27519; number_of_response:1; }","duration":"105.622005ms","start":"2026-07-15T05:49:25.51803Z","end":"2026-07-15T05:49:25.623652Z","steps":["trace[1972030167] 'process raft request' (duration: 105.492841ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T05:50:40.390538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25232} {"level":"info","ts":"2026-07-15T05:50:40.526296Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25232,"took":"132.843015ms","hash":3085644337,"current-db-size-bytes":60198912,"current-db-size":"60 MB","current-db-size-in-use-bytes":39575552,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-07-15T05:50:40.52635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3085644337,"revision":25232,"compact-revision":22011} {"level":"info","ts":"2026-07-15T05:52:02.161795Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T05:52:02.167617Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T05:52:02.16789Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T05:55:40.398286Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28328} {"level":"info","ts":"2026-07-15T05:55:40.544096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28328,"took":"142.06602ms","hash":2863716941,"current-db-size-bytes":60198912,"current-db-size":"60 MB","current-db-size-in-use-bytes":33349632,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T05:55:40.5442Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2863716941,"revision":28328,"compact-revision":25232} {"level":"info","ts":"2026-07-15T06:00:40.405467Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30719} {"level":"info","ts":"2026-07-15T06:00:40.542189Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30719,"took":"132.849535ms","hash":3285473219,"current-db-size-bytes":60198912,"current-db-size":"60 MB","current-db-size-in-use-bytes":31997952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T06:00:40.542266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3285473219,"revision":30719,"compact-revision":28328} {"level":"info","ts":"2026-07-15T06:05:40.410975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33054} {"level":"info","ts":"2026-07-15T06:05:40.564397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33054,"took":"149.568652ms","hash":2559061143,"current-db-size-bytes":61243392,"current-db-size":"61 MB","current-db-size-in-use-bytes":32641024,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-15T06:05:40.564471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2559061143,"revision":33054,"compact-revision":30719} {"level":"info","ts":"2026-07-15T06:10:40.417802Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35368} {"level":"info","ts":"2026-07-15T06:10:40.56833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35368,"took":"144.720175ms","hash":3248458989,"current-db-size-bytes":61706240,"current-db-size":"62 MB","current-db-size-in-use-bytes":31117312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T06:10:40.568451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3248458989,"revision":35368,"compact-revision":33054} {"level":"info","ts":"2026-07-15T06:13:08.76688Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T06:13:08.771879Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T06:13:08.771952Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}