{"level":"warn","ts":"2026-07-08T02:15:44.618704Z","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-08T02:15:44.618833Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-08T02:15:44.618927Z","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-08T02:15:44.618946Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-08T02:15:44.618972Z","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-08T02:15:44.619905Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-08T02:15:44.62002Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-08T02:15:44.623732Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.383047ms"} {"level":"info","ts":"2026-07-08T02:15:44.629166Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-08T02:15:44.629242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T02:15:44.629261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-08T02:15:44.629271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T02:15:44.629279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-08T02:15:44.629323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-08T02:15:44.633447Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T02:15:44.634787Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T02:15:44.636253Z","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-08T02:15:44.638833Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T02:15:44.639114Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T02:15:44.639276Z","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-08T02:15:44.639331Z","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-08T02:15:44.639342Z","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-08T02:15:44.640065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-08T02:15:44.641076Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-08T02:15:44.647601Z","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-08T02:15:44.6478Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-08T02:15:44.647887Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T02:15:44.647863Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-08T02:15:44.647929Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T02:15:44.930494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T02:15:44.930544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T02:15:44.930572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-08T02:15:44.930646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-08T02:15:44.930659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-08T02:15:44.930671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-08T02:15:44.93068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-08T02:15:44.931592Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:44.932309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:15:44.93231Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T02:15:44.932374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T02:15:44.933021Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T02:15:44.933042Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T02:15:44.933373Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:44.933473Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:44.933508Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T02:15:44.934943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T02:15:44.935385Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"warn","ts":"2026-07-08T02:16:25.564623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.669683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:16:25.565042Z","caller":"traceutil/trace.go:171","msg":"trace[1228610533] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:586; }","duration":"200.786021ms","start":"2026-07-08T02:16:25.364239Z","end":"2026-07-08T02:16:25.565025Z","steps":["trace[1228610533] 'read index received' (duration: 75.238023ms)","trace[1228610533] 'applied index is now lower than readState.Index' (duration: 125.547018ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:16:25.565051Z","caller":"traceutil/trace.go:171","msg":"trace[1660386315] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"266.730536ms","start":"2026-07-08T02:16:25.29829Z","end":"2026-07-08T02:16:25.56502Z","steps":["trace[1660386315] 'process raft request' (duration: 266.502289ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:16:25.565088Z","caller":"traceutil/trace.go:171","msg":"trace[2136610783] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"279.174832ms","start":"2026-07-08T02:16:25.285746Z","end":"2026-07-08T02:16:25.56492Z","steps":["trace[2136610783] 'process raft request' (duration: 153.620054ms)","trace[2136610783] 'compare' (duration: 124.452726ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:16:25.565122Z","caller":"traceutil/trace.go:171","msg":"trace[1339899486] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"186.18701ms","start":"2026-07-08T02:16:25.378886Z","end":"2026-07-08T02:16:25.565073Z","steps":["trace[1339899486] 'process raft request' (duration: 186.071757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:25.565153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.924844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:16:25.565329Z","caller":"traceutil/trace.go:171","msg":"trace[827789007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"201.157391ms","start":"2026-07-08T02:16:25.364155Z","end":"2026-07-08T02:16:25.565313Z","steps":["trace[827789007] 'agreement among raft nodes before linearized reading' (duration: 200.939315ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:16:25.565094Z","caller":"traceutil/trace.go:171","msg":"trace[927490495] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"263.331098ms","start":"2026-07-08T02:16:25.301728Z","end":"2026-07-08T02:16:25.565059Z","steps":["trace[927490495] 'process raft request' (duration: 263.145762ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:16:25.72959Z","caller":"traceutil/trace.go:171","msg":"trace[1620507437] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"103.182045ms","start":"2026-07-08T02:16:25.626369Z","end":"2026-07-08T02:16:25.729551Z","steps":["trace[1620507437] 'process raft request' (duration: 69.450286ms)","trace[1620507437] 'compare' (duration: 33.495292ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:16:32.252169Z","caller":"traceutil/trace.go:171","msg":"trace[1059637434] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"225.192902ms","start":"2026-07-08T02:16:32.026939Z","end":"2026-07-08T02:16:32.252132Z","steps":["trace[1059637434] 'process raft request' (duration: 224.461301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:32.796345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.97109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:16:32.796576Z","caller":"traceutil/trace.go:171","msg":"trace[1961786742] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"433.501106ms","start":"2026-07-08T02:16:32.363053Z","end":"2026-07-08T02:16:32.796554Z","steps":["trace[1961786742] 'read index received' (duration: 243.252669ms)","trace[1961786742] 'applied index is now lower than readState.Index' (duration: 190.247097ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:16:32.796724Z","caller":"traceutil/trace.go:171","msg":"trace[1630126586] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"518.606983ms","start":"2026-07-08T02:16:32.278096Z","end":"2026-07-08T02:16:32.796703Z","steps":["trace[1630126586] 'process raft request' (duration: 328.195421ms)","trace[1630126586] 'compare' (duration: 189.67232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:16:32.796834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:32.278062Z","time spent":"518.721346ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:16:32.796903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.830016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:16:32.796979Z","caller":"traceutil/trace.go:171","msg":"trace[19823142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"433.97036ms","start":"2026-07-08T02:16:32.362988Z","end":"2026-07-08T02:16:32.796959Z","steps":["trace[19823142] 'agreement among raft nodes before linearized reading' (duration: 433.759364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:32.797022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:32.362955Z","time spent":"434.058972ms","remote":"127.0.0.1:44038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T02:16:32.797037Z","caller":"traceutil/trace.go:171","msg":"trace[1328309900] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"477.044228ms","start":"2026-07-08T02:16:32.319954Z","end":"2026-07-08T02:16:32.796998Z","steps":["trace[1328309900] 'process raft request' (duration: 476.534013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:32.797059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.479292ms","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-08T02:16:32.797158Z","caller":"traceutil/trace.go:171","msg":"trace[18721753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"365.583095ms","start":"2026-07-08T02:16:32.431551Z","end":"2026-07-08T02:16:32.797134Z","steps":["trace[18721753] 'agreement among raft nodes before linearized reading' (duration: 365.431661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:16:32.797184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:32.31993Z","time spent":"477.177772ms","remote":"127.0.0.1:44270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T02:16:32.797207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:16:32.431482Z","time spent":"365.715089ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T02:16:32.797246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.715046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T02:16:32.797364Z","caller":"traceutil/trace.go:171","msg":"trace[2028368220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"162.884401ms","start":"2026-07-08T02:16:32.634447Z","end":"2026-07-08T02:16:32.797332Z","steps":["trace[2028368220] 'agreement among raft nodes before linearized reading' (duration: 162.554111ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:17:21.430097Z","caller":"traceutil/trace.go:171","msg":"trace[1127169506] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"131.844654ms","start":"2026-07-08T02:17:21.298225Z","end":"2026-07-08T02:17:21.43007Z","steps":["trace[1127169506] 'process raft request' (duration: 131.685789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:17:41.702989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.597743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-pt58f\" ","response":"range_response_count:1 size:4178"} {"level":"info","ts":"2026-07-08T02:17:41.703081Z","caller":"traceutil/trace.go:171","msg":"trace[2019804606] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-pt58f; range_end:; response_count:1; response_revision:1477; }","duration":"121.748687ms","start":"2026-07-08T02:17:41.581312Z","end":"2026-07-08T02:17:41.703061Z","steps":["trace[2019804606] 'range keys from in-memory index tree' (duration: 121.409968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:09.762932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.822997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:18:09.763052Z","caller":"traceutil/trace.go:171","msg":"trace[1088256446] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1978; }","duration":"148.35062ms","start":"2026-07-08T02:18:09.614684Z","end":"2026-07-08T02:18:09.763034Z","steps":["trace[1088256446] 'read index received' (duration: 24.277437ms)","trace[1088256446] 'applied index is now lower than readState.Index' (duration: 124.072013ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:18:09.763101Z","caller":"traceutil/trace.go:171","msg":"trace[1451731363] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"150.303916ms","start":"2026-07-08T02:18:09.612764Z","end":"2026-07-08T02:18:09.763068Z","steps":["trace[1451731363] 'process raft request' (duration: 26.252734ms)","trace[1451731363] 'compare' (duration: 123.696492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:18:09.763254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.563666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c02ef628d0354b\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-07-08T02:18:09.763309Z","caller":"traceutil/trace.go:171","msg":"trace[1762100198] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c02ef628d0354b; range_end:; response_count:1; response_revision:1912; }","duration":"148.640429ms","start":"2026-07-08T02:18:09.614651Z","end":"2026-07-08T02:18:09.763291Z","steps":["trace[1762100198] 'agreement among raft nodes before linearized reading' (duration: 148.485384ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:22.951789Z","caller":"traceutil/trace.go:171","msg":"trace[477428633] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"135.309312ms","start":"2026-07-08T02:18:22.816457Z","end":"2026-07-08T02:18:22.951766Z","steps":["trace[477428633] 'read index received' (duration: 135.221779ms)","trace[477428633] 'applied index is now lower than readState.Index' (duration: 86.503µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:18:22.95183Z","caller":"traceutil/trace.go:171","msg":"trace[954755981] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"142.981259ms","start":"2026-07-08T02:18:22.808822Z","end":"2026-07-08T02:18:22.951803Z","steps":["trace[954755981] 'process raft request' (duration: 142.809774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:18:22.952134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.659391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-08T02:18:22.952177Z","caller":"traceutil/trace.go:171","msg":"trace[718807084] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2018; }","duration":"135.745224ms","start":"2026-07-08T02:18:22.816418Z","end":"2026-07-08T02:18:22.952163Z","steps":["trace[718807084] 'agreement among raft nodes before linearized reading' (duration: 135.464906ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:18:22.955016Z","caller":"traceutil/trace.go:171","msg":"trace[76779846] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"134.754256ms","start":"2026-07-08T02:18:22.820239Z","end":"2026-07-08T02:18:22.954993Z","steps":["trace[76779846] 'process raft request' (duration: 134.597631ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:19:05.048569Z","caller":"traceutil/trace.go:171","msg":"trace[1884298196] transaction","detail":"{read_only:false; response_revision:2258; number_of_response:1; }","duration":"148.634899ms","start":"2026-07-08T02:19:04.899903Z","end":"2026-07-08T02:19:05.048538Z","steps":["trace[1884298196] 'process raft request' (duration: 148.486825ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:19:05.076095Z","caller":"traceutil/trace.go:171","msg":"trace[1055193903] transaction","detail":"{read_only:false; response_revision:2259; number_of_response:1; }","duration":"169.267333ms","start":"2026-07-08T02:19:04.906803Z","end":"2026-07-08T02:19:05.076071Z","steps":["trace[1055193903] 'process raft request' (duration: 169.14177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:20:14.275918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.533812ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:20:14.276146Z","caller":"traceutil/trace.go:171","msg":"trace[1268417332] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2831; }","duration":"169.80241ms","start":"2026-07-08T02:20:14.106316Z","end":"2026-07-08T02:20:14.276118Z","steps":["trace[1268417332] 'range keys from in-memory index tree' (duration: 169.501561ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:20:14.286309Z","caller":"traceutil/trace.go:171","msg":"trace[287563778] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"273.253151ms","start":"2026-07-08T02:20:14.013018Z","end":"2026-07-08T02:20:14.286271Z","steps":["trace[287563778] 'process raft request' (duration: 273.169798ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:20:14.286323Z","caller":"traceutil/trace.go:171","msg":"trace[309278533] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"273.504958ms","start":"2026-07-08T02:20:14.012788Z","end":"2026-07-08T02:20:14.286293Z","steps":["trace[309278533] 'process raft request' (duration: 273.152708ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:22:44.938155Z","caller":"traceutil/trace.go:171","msg":"trace[659569043] transaction","detail":"{read_only:false; response_revision:3826; number_of_response:1; }","duration":"155.143649ms","start":"2026-07-08T02:22:44.782984Z","end":"2026-07-08T02:22:44.938128Z","steps":["trace[659569043] 'process raft request' (duration: 154.982125ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:16.120682Z","caller":"traceutil/trace.go:171","msg":"trace[94825869] linearizableReadLoop","detail":"{readStateIndex:5100; appliedIndex:5099; }","duration":"110.200301ms","start":"2026-07-08T02:24:16.01046Z","end":"2026-07-08T02:24:16.12066Z","steps":["trace[94825869] 'read index received' (duration: 110.008855ms)","trace[94825869] 'applied index is now lower than readState.Index' (duration: 190.526µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:16.120793Z","caller":"traceutil/trace.go:171","msg":"trace[1923537504] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"117.327079ms","start":"2026-07-08T02:24:16.003458Z","end":"2026-07-08T02:24:16.120785Z","steps":["trace[1923537504] 'process raft request' (duration: 117.070332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:16.121233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.738875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-07-08T02:24:16.12132Z","caller":"traceutil/trace.go:171","msg":"trace[1605406863] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4918; }","duration":"110.9032ms","start":"2026-07-08T02:24:16.010398Z","end":"2026-07-08T02:24:16.121301Z","steps":["trace[1605406863] 'agreement among raft nodes before linearized reading' (duration: 110.53179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.044842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.495428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:24:17.045101Z","caller":"traceutil/trace.go:171","msg":"trace[1531752522] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"649.622543ms","start":"2026-07-08T02:24:16.395445Z","end":"2026-07-08T02:24:17.045067Z","steps":["trace[1531752522] 'process raft request' (duration: 61.778046ms)","trace[1531752522] 'compare' (duration: 586.645124ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:17.045147Z","caller":"traceutil/trace.go:171","msg":"trace[1912866789] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"568.764848ms","start":"2026-07-08T02:24:16.476361Z","end":"2026-07-08T02:24:17.045126Z","steps":["trace[1912866789] 'process raft request' (duration: 568.637764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.045256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.395425Z","time spent":"649.753207ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:24:17.045262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.47633Z","time spent":"568.86316ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:24:17.471212Z","caller":"traceutil/trace.go:171","msg":"trace[811304487] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"822.774493ms","start":"2026-07-08T02:24:16.648395Z","end":"2026-07-08T02:24:17.47117Z","steps":["trace[811304487] 'process raft request' (duration: 822.606898ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.471276Z","caller":"traceutil/trace.go:171","msg":"trace[555820286] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"804.208546ms","start":"2026-07-08T02:24:16.667048Z","end":"2026-07-08T02:24:17.471257Z","steps":["trace[555820286] 'process raft request' (duration: 804.018071ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.471289Z","caller":"traceutil/trace.go:171","msg":"trace[1094114165] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"883.411157ms","start":"2026-07-08T02:24:16.587864Z","end":"2026-07-08T02:24:17.471276Z","steps":["trace[1094114165] 'process raft request' (duration: 881.597797ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:17.471301Z","caller":"traceutil/trace.go:171","msg":"trace[684931149] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5103; }","duration":"863.695169ms","start":"2026-07-08T02:24:16.607583Z","end":"2026-07-08T02:24:17.471278Z","steps":["trace[684931149] 'read index received' (duration: 436.061641ms)","trace[684931149] 'applied index is now lower than readState.Index' (duration: 427.630768ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:24:17.471405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.587839Z","time spent":"883.475219ms","remote":"127.0.0.1:43360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":413490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:24:17.471404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.648356Z","time spent":"822.923397ms","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":904,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-08T02:24:17.471424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.667037Z","time spent":"804.274489ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:24:17.471604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.780279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T02:24:17.471628Z","caller":"traceutil/trace.go:171","msg":"trace[767972249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4927; }","duration":"271.83693ms","start":"2026-07-08T02:24:17.199783Z","end":"2026-07-08T02:24:17.47162Z","steps":["trace[767972249] 'agreement among raft nodes before linearized reading' (duration: 271.741748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.421769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:17.472694Z","caller":"traceutil/trace.go:171","msg":"trace[634818309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4927; }","duration":"110.474118ms","start":"2026-07-08T02:24:17.36221Z","end":"2026-07-08T02:24:17.472684Z","steps":["trace[634818309] 'agreement among raft nodes before linearized reading' (duration: 109.420549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.432328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-p6pzc\" ","response":"range_response_count:1 size:9753"} {"level":"info","ts":"2026-07-08T02:24:17.472785Z","caller":"traceutil/trace.go:171","msg":"trace[887405030] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-p6pzc; range_end:; response_count:1; response_revision:4927; }","duration":"825.611021ms","start":"2026-07-08T02:24:16.647157Z","end":"2026-07-08T02:24:17.472768Z","steps":["trace[887405030] 'agreement among raft nodes before linearized reading' (duration: 824.329276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.472831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.647118Z","time spent":"825.696664ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9776,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-p6pzc\" "} {"level":"warn","ts":"2026-07-08T02:24:17.471747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.751692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:24:17.472937Z","caller":"traceutil/trace.go:171","msg":"trace[1938449936] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:4927; }","duration":"423.948285ms","start":"2026-07-08T02:24:17.048956Z","end":"2026-07-08T02:24:17.472904Z","steps":["trace[1938449936] 'agreement among raft nodes before linearized reading' (duration: 422.749612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.783387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-08T02:24:17.472988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:17.048944Z","time spent":"424.029027ms","remote":"127.0.0.1:44444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-07-08T02:24:17.473005Z","caller":"traceutil/trace.go:171","msg":"trace[667350373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4927; }","duration":"269.029623ms","start":"2026-07-08T02:24:17.203963Z","end":"2026-07-08T02:24:17.472992Z","steps":["trace[667350373] 'agreement among raft nodes before linearized reading' (duration: 267.736367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.471836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.903165ms","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-08T02:24:17.473097Z","caller":"traceutil/trace.go:171","msg":"trace[426436242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4927; }","duration":"730.185711ms","start":"2026-07-08T02:24:16.742892Z","end":"2026-07-08T02:24:17.473077Z","steps":["trace[426436242] 'agreement among raft nodes before linearized reading' (duration: 728.875165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.473154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.742875Z","time spent":"730.261253ms","remote":"127.0.0.1:44288","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-08T02:24:17.47188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.146666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-08T02:24:17.473215Z","caller":"traceutil/trace.go:171","msg":"trace[1661606842] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4927; }","duration":"670.527154ms","start":"2026-07-08T02:24:16.802669Z","end":"2026-07-08T02:24:17.473196Z","steps":["trace[1661606842] 'agreement among raft nodes before linearized reading' (duration: 669.053843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.473269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.80265Z","time spent":"670.600506ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-08T02:24:17.471193Z","caller":"traceutil/trace.go:171","msg":"trace[365879971] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"178.140778ms","start":"2026-07-08T02:24:17.293024Z","end":"2026-07-08T02:24:17.471165Z","steps":["trace[365879971] 'process raft request' (duration: 178.097577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.472355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.746968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2209844"} {"level":"info","ts":"2026-07-08T02:24:17.473419Z","caller":"traceutil/trace.go:171","msg":"trace[233885517] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4927; }","duration":"865.860579ms","start":"2026-07-08T02:24:16.607544Z","end":"2026-07-08T02:24:17.473405Z","steps":["trace[233885517] 'agreement among raft nodes before linearized reading' (duration: 863.893904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:17.473468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:24:16.607527Z","time spent":"865.924791ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":50,"response size":2209867,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-07-08T02:24:21.07874Z","caller":"traceutil/trace.go:171","msg":"trace[333060564] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"232.244244ms","start":"2026-07-08T02:24:20.84647Z","end":"2026-07-08T02:24:21.078714Z","steps":["trace[333060564] 'read index received' (duration: 232.019297ms)","trace[333060564] 'applied index is now lower than readState.Index' (duration: 223.797µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:21.078848Z","caller":"traceutil/trace.go:171","msg":"trace[1655581090] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"254.384539ms","start":"2026-07-08T02:24:20.824425Z","end":"2026-07-08T02:24:21.07881Z","steps":["trace[1655581090] 'process raft request' (duration: 254.05393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:21.079344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.856651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T02:24:21.079418Z","caller":"traceutil/trace.go:171","msg":"trace[1566898874] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5078; }","duration":"233.009915ms","start":"2026-07-08T02:24:20.846394Z","end":"2026-07-08T02:24:21.079404Z","steps":["trace[1566898874] 'agreement among raft nodes before linearized reading' (duration: 232.454049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:21.08232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.016364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cc855d98-41fa-47ce-a6ce-f3c5bae97a1f\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-08T02:24:21.082393Z","caller":"traceutil/trace.go:171","msg":"trace[689354483] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cc855d98-41fa-47ce-a6ce-f3c5bae97a1f; range_end:; response_count:1; response_revision:5079; }","duration":"211.188768ms","start":"2026-07-08T02:24:20.871184Z","end":"2026-07-08T02:24:21.082372Z","steps":["trace[689354483] 'agreement among raft nodes before linearized reading' (duration: 210.956112ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:24.170591Z","caller":"traceutil/trace.go:171","msg":"trace[68680326] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"296.197761ms","start":"2026-07-08T02:24:23.874361Z","end":"2026-07-08T02:24:24.170559Z","steps":["trace[68680326] 'process raft request' (duration: 294.531805ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:33.661062Z","caller":"traceutil/trace.go:171","msg":"trace[1458688701] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"100.926759ms","start":"2026-07-08T02:24:33.560109Z","end":"2026-07-08T02:24:33.661036Z","steps":["trace[1458688701] 'process raft request' (duration: 27.578128ms)","trace[1458688701] 'compare' (duration: 73.113054ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:33.862716Z","caller":"traceutil/trace.go:171","msg":"trace[1376073215] linearizableReadLoop","detail":"{readStateIndex:5507; appliedIndex:5505; }","duration":"122.042976ms","start":"2026-07-08T02:24:33.740647Z","end":"2026-07-08T02:24:33.86269Z","steps":["trace[1376073215] 'read index received' (duration: 10.993526ms)","trace[1376073215] 'applied index is now lower than readState.Index' (duration: 111.04858ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:33.862829Z","caller":"traceutil/trace.go:171","msg":"trace[753287586] transaction","detail":"{read_only:false; response_revision:5313; number_of_response:1; }","duration":"122.656193ms","start":"2026-07-08T02:24:33.740146Z","end":"2026-07-08T02:24:33.862802Z","steps":["trace[753287586] 'process raft request' (duration: 122.387016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:33.863085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.399756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:70786"} {"level":"info","ts":"2026-07-08T02:24:33.863152Z","caller":"traceutil/trace.go:171","msg":"trace[380142636] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5313; }","duration":"122.552311ms","start":"2026-07-08T02:24:33.740584Z","end":"2026-07-08T02:24:33.863136Z","steps":["trace[380142636] 'agreement among raft nodes before linearized reading' (duration: 122.241652ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:34.071711Z","caller":"traceutil/trace.go:171","msg":"trace[1430532880] linearizableReadLoop","detail":"{readStateIndex:5508; appliedIndex:5507; }","duration":"177.979403ms","start":"2026-07-08T02:24:33.8937Z","end":"2026-07-08T02:24:34.07168Z","steps":["trace[1430532880] 'read index received' (duration: 177.641573ms)","trace[1430532880] 'applied index is now lower than readState.Index' (duration: 336.4µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:34.071866Z","caller":"traceutil/trace.go:171","msg":"trace[281143628] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"198.035161ms","start":"2026-07-08T02:24:33.873809Z","end":"2026-07-08T02:24:34.071844Z","steps":["trace[281143628] 'process raft request' (duration: 197.650011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:34.071941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.215849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:24:34.072006Z","caller":"traceutil/trace.go:171","msg":"trace[476650094] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5314; }","duration":"178.304551ms","start":"2026-07-08T02:24:33.893681Z","end":"2026-07-08T02:24:34.071986Z","steps":["trace[476650094] 'agreement among raft nodes before linearized reading' (duration: 178.179918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:34.072038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.023504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:24:34.072126Z","caller":"traceutil/trace.go:171","msg":"trace[1115182347] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:5314; }","duration":"141.177039ms","start":"2026-07-08T02:24:33.930928Z","end":"2026-07-08T02:24:34.072105Z","steps":["trace[1115182347] 'agreement among raft nodes before linearized reading' (duration: 140.985503ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:41.818799Z","caller":"traceutil/trace.go:171","msg":"trace[866631180] linearizableReadLoop","detail":"{readStateIndex:5645; appliedIndex:5643; }","duration":"102.888513ms","start":"2026-07-08T02:24:41.715887Z","end":"2026-07-08T02:24:41.818776Z","steps":["trace[866631180] 'read index received' (duration: 27.110625ms)","trace[866631180] 'applied index is now lower than readState.Index' (duration: 75.776528ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:41.818995Z","caller":"traceutil/trace.go:171","msg":"trace[508074711] transaction","detail":"{read_only:false; response_revision:5450; number_of_response:1; }","duration":"120.530114ms","start":"2026-07-08T02:24:41.698411Z","end":"2026-07-08T02:24:41.818941Z","steps":["trace[508074711] 'process raft request' (duration: 105.960269ms)","trace[508074711] 'compare' (duration: 14.139193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:24:41.819188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.281014ms","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-08T02:24:41.819234Z","caller":"traceutil/trace.go:171","msg":"trace[1353507121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5450; }","duration":"103.406898ms","start":"2026-07-08T02:24:41.715817Z","end":"2026-07-08T02:24:41.819224Z","steps":["trace[1353507121] 'agreement among raft nodes before linearized reading' (duration: 103.12793ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:42.017239Z","caller":"traceutil/trace.go:171","msg":"trace[1231367798] linearizableReadLoop","detail":"{readStateIndex:5648; appliedIndex:5647; }","duration":"110.639508ms","start":"2026-07-08T02:24:41.906572Z","end":"2026-07-08T02:24:42.017212Z","steps":["trace[1231367798] 'read index received' (duration: 78.559765ms)","trace[1231367798] 'applied index is now lower than readState.Index' (duration: 32.078733ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:42.017535Z","caller":"traceutil/trace.go:171","msg":"trace[2073007501] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"111.100022ms","start":"2026-07-08T02:24:41.90642Z","end":"2026-07-08T02:24:42.01752Z","steps":["trace[2073007501] 'process raft request' (duration: 78.793793ms)","trace[2073007501] 'compare' (duration: 31.680041ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:24:42.017764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.165963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-527f1386-2a96-4691-98c9-dfdb4204c657\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-07-08T02:24:42.017799Z","caller":"traceutil/trace.go:171","msg":"trace[1530677715] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-527f1386-2a96-4691-98c9-dfdb4204c657; range_end:; response_count:1; response_revision:5453; }","duration":"111.232015ms","start":"2026-07-08T02:24:41.906558Z","end":"2026-07-08T02:24:42.01779Z","steps":["trace[1530677715] 'agreement among raft nodes before linearized reading' (duration: 111.145613ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:24:44.174684Z","caller":"traceutil/trace.go:171","msg":"trace[216563354] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"185.696527ms","start":"2026-07-08T02:24:43.98895Z","end":"2026-07-08T02:24:44.174646Z","steps":["trace[216563354] 'process raft request' (duration: 162.011718ms)","trace[216563354] 'compare' (duration: 23.434482ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:44.174969Z","caller":"traceutil/trace.go:171","msg":"trace[2046169831] linearizableReadLoop","detail":"{readStateIndex:5691; appliedIndex:5689; }","duration":"181.414328ms","start":"2026-07-08T02:24:43.993505Z","end":"2026-07-08T02:24:44.17492Z","steps":["trace[2046169831] 'read index received' (duration: 157.475372ms)","trace[2046169831] 'applied index is now lower than readState.Index' (duration: 23.937106ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:24:44.175098Z","caller":"traceutil/trace.go:171","msg":"trace[97098155] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5493; }","duration":"184.521405ms","start":"2026-07-08T02:24:43.990548Z","end":"2026-07-08T02:24:44.17507Z","steps":["trace[97098155] 'process raft request' (duration: 184.219447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:44.175324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.775008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-08T02:24:44.175403Z","caller":"traceutil/trace.go:171","msg":"trace[32631023] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5493; }","duration":"181.907602ms","start":"2026-07-08T02:24:43.993481Z","end":"2026-07-08T02:24:44.175388Z","steps":["trace[32631023] 'agreement among raft nodes before linearized reading' (duration: 181.655825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:24:44.175537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.064725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-527f1386-2a96-4691-98c9-dfdb4204c657\" ","response":"range_response_count:1 size:3948"} {"level":"info","ts":"2026-07-08T02:24:44.175621Z","caller":"traceutil/trace.go:171","msg":"trace[198040358] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-527f1386-2a96-4691-98c9-dfdb4204c657; range_end:; response_count:1; response_revision:5493; }","duration":"146.218309ms","start":"2026-07-08T02:24:44.02938Z","end":"2026-07-08T02:24:44.175598Z","steps":["trace[198040358] 'agreement among raft nodes before linearized reading' (duration: 145.926121ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:25:44.967108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-07-08T02:25:45.046487Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"77.728223ms","hash":2651681678,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":33800192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-08T02:25:45.046537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2651681678,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-07-08T02:30:44.973105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6079} {"level":"info","ts":"2026-07-08T02:30:45.0636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6079,"took":"88.76059ms","hash":132879183,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":21901312,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-08T02:30:45.063696Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":132879183,"revision":6079,"compact-revision":3000} {"level":"info","ts":"2026-07-08T02:31:08.72294Z","caller":"traceutil/trace.go:171","msg":"trace[1177683518] transaction","detail":"{read_only:false; response_revision:8426; number_of_response:1; }","duration":"177.150346ms","start":"2026-07-08T02:31:08.54575Z","end":"2026-07-08T02:31:08.7229Z","steps":["trace[1177683518] 'process raft request' (duration: 176.886919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:09.228157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.507021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:09.228302Z","caller":"traceutil/trace.go:171","msg":"trace[1544231686] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:8429; }","duration":"152.702056ms","start":"2026-07-08T02:31:09.075555Z","end":"2026-07-08T02:31:09.228257Z","steps":["trace[1544231686] 'count revisions from in-memory index tree' (duration: 152.326016ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:09.230137Z","caller":"traceutil/trace.go:171","msg":"trace[1141871013] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"104.729103ms","start":"2026-07-08T02:31:09.125386Z","end":"2026-07-08T02:31:09.230115Z","steps":["trace[1141871013] 'process raft request' (duration: 104.494347ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:09.851478Z","caller":"traceutil/trace.go:171","msg":"trace[512613655] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"105.475793ms","start":"2026-07-08T02:31:09.74597Z","end":"2026-07-08T02:31:09.851446Z","steps":["trace[512613655] 'process raft request' (duration: 14.782061ms)","trace[512613655] 'compare' (duration: 90.554158ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:09.923884Z","caller":"traceutil/trace.go:171","msg":"trace[1242254244] linearizableReadLoop","detail":"{readStateIndex:8726; appliedIndex:8725; }","duration":"150.540776ms","start":"2026-07-08T02:31:09.773313Z","end":"2026-07-08T02:31:09.923853Z","steps":["trace[1242254244] 'read index received' (duration: 150.264938ms)","trace[1242254244] 'applied index is now lower than readState.Index' (duration: 274.678µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:09.924016Z","caller":"traceutil/trace.go:171","msg":"trace[1329295474] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"177.105495ms","start":"2026-07-08T02:31:09.746874Z","end":"2026-07-08T02:31:09.923979Z","steps":["trace[1329295474] 'process raft request' (duration: 176.826508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:09.924139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.801844ms","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-08T02:31:09.924213Z","caller":"traceutil/trace.go:171","msg":"trace[598267897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8433; }","duration":"150.920656ms","start":"2026-07-08T02:31:09.773275Z","end":"2026-07-08T02:31:09.924196Z","steps":["trace[598267897] 'agreement among raft nodes before linearized reading' (duration: 150.728661ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:12.063268Z","caller":"traceutil/trace.go:171","msg":"trace[346542749] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"100.400062ms","start":"2026-07-08T02:31:11.96284Z","end":"2026-07-08T02:31:12.06324Z","steps":["trace[346542749] 'process raft request' (duration: 100.040842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:35.683125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.422897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:35.68323Z","caller":"traceutil/trace.go:171","msg":"trace[573768618] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8621; }","duration":"112.575711ms","start":"2026-07-08T02:31:35.570632Z","end":"2026-07-08T02:31:35.683208Z","steps":["trace[573768618] 'count revisions from in-memory index tree' (duration: 112.337094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:39.600775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.297915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:31:39.601486Z","caller":"traceutil/trace.go:171","msg":"trace[608530079] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8641; }","duration":"833.022815ms","start":"2026-07-08T02:31:38.768435Z","end":"2026-07-08T02:31:39.601458Z","steps":["trace[608530079] 'count revisions from in-memory index tree' (duration: 832.224693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:39.601647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:38.768384Z","time spent":"833.19393ms","remote":"127.0.0.1:44408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-08T02:31:39.602745Z","caller":"traceutil/trace.go:171","msg":"trace[1762161342] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"784.270509ms","start":"2026-07-08T02:31:38.818439Z","end":"2026-07-08T02:31:39.602709Z","steps":["trace[1762161342] 'process raft request' (duration: 754.664336ms)","trace[1762161342] 'compare' (duration: 29.164071ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:39.602756Z","caller":"traceutil/trace.go:171","msg":"trace[778870990] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"680.507534ms","start":"2026-07-08T02:31:38.92222Z","end":"2026-07-08T02:31:39.602727Z","steps":["trace[778870990] 'process raft request' (duration: 680.3699ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:39.602786Z","caller":"traceutil/trace.go:171","msg":"trace[1899008361] linearizableReadLoop","detail":"{readStateIndex:8946; appliedIndex:8943; }","duration":"406.058102ms","start":"2026-07-08T02:31:39.196709Z","end":"2026-07-08T02:31:39.602768Z","steps":["trace[1899008361] 'read index received' (duration: 376.389057ms)","trace[1899008361] 'applied index is now lower than readState.Index' (duration: 29.668155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:39.602914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:38.818415Z","time spent":"784.425684ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:31:39.602931Z","caller":"traceutil/trace.go:171","msg":"trace[743941117] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"427.057936ms","start":"2026-07-08T02:31:39.17586Z","end":"2026-07-08T02:31:39.602918Z","steps":["trace[743941117] 'process raft request' (duration: 426.82846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:39.60295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:38.922202Z","time spent":"680.663249ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:31:39.603001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:39.175837Z","time spent":"427.117177ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T02:31:39.603141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.795748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:39.603182Z","caller":"traceutil/trace.go:171","msg":"trace[1645128230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8644; }","duration":"239.87814ms","start":"2026-07-08T02:31:39.36329Z","end":"2026-07-08T02:31:39.603168Z","steps":["trace[1645128230] 'agreement among raft nodes before linearized reading' (duration: 239.814349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:39.603183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.475843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-07-08T02:31:39.603227Z","caller":"traceutil/trace.go:171","msg":"trace[1972454645] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:8644; }","duration":"406.543406ms","start":"2026-07-08T02:31:39.196672Z","end":"2026-07-08T02:31:39.603215Z","steps":["trace[1972454645] 'agreement among raft nodes before linearized reading' (duration: 406.423402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:39.603255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:31:39.196656Z","time spent":"406.592717ms","remote":"127.0.0.1:44200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-07-08T02:31:42.122117Z","caller":"traceutil/trace.go:171","msg":"trace[108208260] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"120.990975ms","start":"2026-07-08T02:31:42.001106Z","end":"2026-07-08T02:31:42.122097Z","steps":["trace[108208260] 'process raft request' (duration: 113.330652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:42.745344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.231286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kghfq\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-07-08T02:31:42.745428Z","caller":"traceutil/trace.go:171","msg":"trace[1256203878] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kghfq; range_end:; response_count:1; response_revision:8663; }","duration":"169.395871ms","start":"2026-07-08T02:31:42.576011Z","end":"2026-07-08T02:31:42.745407Z","steps":["trace[1256203878] 'range keys from in-memory index tree' (duration: 169.067002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.367687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.515656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:31:52.367794Z","caller":"traceutil/trace.go:171","msg":"trace[550175212] linearizableReadLoop","detail":"{readStateIndex:9059; appliedIndex:9058; }","duration":"102.414757ms","start":"2026-07-08T02:31:52.265362Z","end":"2026-07-08T02:31:52.367776Z","steps":["trace[550175212] 'read index received' (duration: 41.251µs)","trace[550175212] 'applied index is now lower than readState.Index' (duration: 102.372356ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:31:52.36786Z","caller":"traceutil/trace.go:171","msg":"trace[358370924] transaction","detail":"{read_only:false; response_revision:8752; number_of_response:1; }","duration":"219.209955ms","start":"2026-07-08T02:31:52.148639Z","end":"2026-07-08T02:31:52.367849Z","steps":["trace[358370924] 'process raft request' (duration: 64.452122ms)","trace[358370924] 'compare' (duration: 154.421333ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:31:52.368017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.649304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T02:31:52.368079Z","caller":"traceutil/trace.go:171","msg":"trace[1831864533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8752; }","duration":"102.754907ms","start":"2026-07-08T02:31:52.265311Z","end":"2026-07-08T02:31:52.368065Z","steps":["trace[1831864533] 'agreement among raft nodes before linearized reading' (duration: 102.602022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:52.389675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.939127ms","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-08T02:31:52.389749Z","caller":"traceutil/trace.go:171","msg":"trace[837796763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8753; }","duration":"105.069802ms","start":"2026-07-08T02:31:52.284664Z","end":"2026-07-08T02:31:52.389734Z","steps":["trace[837796763] 'agreement among raft nodes before linearized reading' (duration: 104.868356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:53.15125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.664852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:53.151368Z","caller":"traceutil/trace.go:171","msg":"trace[1545429387] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:8760; }","duration":"126.898119ms","start":"2026-07-08T02:31:53.024443Z","end":"2026-07-08T02:31:53.151341Z","steps":["trace[1545429387] 'count revisions from in-memory index tree' (duration: 126.566399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:53.151271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.188264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:31:53.151494Z","caller":"traceutil/trace.go:171","msg":"trace[1105556677] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8760; }","duration":"119.450522ms","start":"2026-07-08T02:31:53.032005Z","end":"2026-07-08T02:31:53.151456Z","steps":["trace[1105556677] 'count revisions from in-memory index tree' (duration: 119.01785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:53.151388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.403477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112179"} {"level":"info","ts":"2026-07-08T02:31:53.151844Z","caller":"traceutil/trace.go:171","msg":"trace[15546475] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8760; }","duration":"166.908641ms","start":"2026-07-08T02:31:52.98492Z","end":"2026-07-08T02:31:53.151829Z","steps":["trace[15546475] 'range keys from in-memory index tree' (duration: 166.15782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:31:53.672281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.075676ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-08T02:31:53.672572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.370688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T02:31:53.672645Z","caller":"traceutil/trace.go:171","msg":"trace[678856899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8761; }","duration":"270.493872ms","start":"2026-07-08T02:31:53.402135Z","end":"2026-07-08T02:31:53.672629Z","steps":["trace[678856899] 'agreement among raft nodes before linearized reading' (duration: 270.365208ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:31:53.672355Z","caller":"traceutil/trace.go:171","msg":"trace[1005897643] linearizableReadLoop","detail":"{readStateIndex:9069; appliedIndex:9068; }","duration":"270.168543ms","start":"2026-07-08T02:31:53.402176Z","end":"2026-07-08T02:31:53.672344Z","steps":["trace[1005897643] 'read index received' (duration: 15.971624ms)","trace[1005897643] 'applied index is now lower than readState.Index' (duration: 254.195939ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:32:04.5875Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023cc.wal"} {"level":"info","ts":"2026-07-08T02:33:08.937478Z","caller":"traceutil/trace.go:171","msg":"trace[10774259] linearizableReadLoop","detail":"{readStateIndex:9830; appliedIndex:9829; }","duration":"247.362578ms","start":"2026-07-08T02:33:08.690089Z","end":"2026-07-08T02:33:08.937452Z","steps":["trace[10774259] 'read index received' (duration: 247.146722ms)","trace[10774259] 'applied index is now lower than readState.Index' (duration: 215.036µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:33:08.937571Z","caller":"traceutil/trace.go:171","msg":"trace[1974852460] transaction","detail":"{read_only:false; response_revision:9503; number_of_response:1; }","duration":"273.27581ms","start":"2026-07-08T02:33:08.664258Z","end":"2026-07-08T02:33:08.937533Z","steps":["trace[1974852460] 'process raft request' (duration: 273.031603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:33:08.937716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.607395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-fb94f659f-6fg2k.18c02fa773e22d46\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-07-08T02:33:08.93779Z","caller":"traceutil/trace.go:171","msg":"trace[1808372856] range","detail":"{range_begin:/registry/events/openstack/barbican-api-fb94f659f-6fg2k.18c02fa773e22d46; range_end:; response_count:1; response_revision:9503; }","duration":"247.718278ms","start":"2026-07-08T02:33:08.690055Z","end":"2026-07-08T02:33:08.937773Z","steps":["trace[1808372856] 'agreement among raft nodes before linearized reading' (duration: 247.499252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:33:08.937874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.069467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T02:33:08.937954Z","caller":"traceutil/trace.go:171","msg":"trace[1142314382] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:9503; }","duration":"116.268762ms","start":"2026-07-08T02:33:08.821667Z","end":"2026-07-08T02:33:08.937936Z","steps":["trace[1142314382] 'agreement among raft nodes before linearized reading' (duration: 116.060477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:33:08.937958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.97306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-08T02:33:08.93802Z","caller":"traceutil/trace.go:171","msg":"trace[491633460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9503; }","duration":"170.073002ms","start":"2026-07-08T02:33:08.767932Z","end":"2026-07-08T02:33:08.938005Z","steps":["trace[491633460] 'agreement among raft nodes before linearized reading' (duration: 169.846546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:33:08.938071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.980847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-08T02:33:08.93818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.407394ms","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-08T02:33:08.938244Z","caller":"traceutil/trace.go:171","msg":"trace[2107078519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9503; }","duration":"164.519637ms","start":"2026-07-08T02:33:08.773706Z","end":"2026-07-08T02:33:08.938225Z","steps":["trace[2107078519] 'agreement among raft nodes before linearized reading' (duration: 164.25771ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:33:08.938183Z","caller":"traceutil/trace.go:171","msg":"trace[102680367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9503; }","duration":"162.180043ms","start":"2026-07-08T02:33:08.775986Z","end":"2026-07-08T02:33:08.938166Z","steps":["trace[102680367] 'agreement among raft nodes before linearized reading' (duration: 161.675839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:33:08.938331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.618823ms","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-08T02:33:08.938375Z","caller":"traceutil/trace.go:171","msg":"trace[35450436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9503; }","duration":"205.662075ms","start":"2026-07-08T02:33:08.732702Z","end":"2026-07-08T02:33:08.938365Z","steps":["trace[35450436] 'agreement among raft nodes before linearized reading' (duration: 204.905974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:33:08.937922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.498801ms","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-08T02:33:08.938551Z","caller":"traceutil/trace.go:171","msg":"trace[674652699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9503; }","duration":"168.103997ms","start":"2026-07-08T02:33:08.770376Z","end":"2026-07-08T02:33:08.938479Z","steps":["trace[674652699] 'agreement among raft nodes before linearized reading' (duration: 167.363987ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:33:29.150939Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T02:33:29.157023Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T02:33:29.157132Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T02:35:44.979227Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8164} {"level":"info","ts":"2026-07-08T02:35:45.038651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8164,"took":"57.942535ms","hash":651425769,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":28430336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T02:35:45.038701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":651425769,"revision":8164,"compact-revision":6079} {"level":"warn","ts":"2026-07-08T02:38:27.398822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.048594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T02:38:27.399044Z","caller":"traceutil/trace.go:171","msg":"trace[2058884252] transaction","detail":"{read_only:false; response_revision:11830; number_of_response:1; }","duration":"233.038625ms","start":"2026-07-08T02:38:27.165972Z","end":"2026-07-08T02:38:27.399011Z","steps":["trace[2058884252] 'process raft request' (duration: 106.697814ms)","trace[2058884252] 'compare' (duration: 125.849927ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:38:27.399243Z","caller":"traceutil/trace.go:171","msg":"trace[271376371] transaction","detail":"{read_only:false; response_revision:11831; number_of_response:1; }","duration":"206.784474ms","start":"2026-07-08T02:38:27.192438Z","end":"2026-07-08T02:38:27.399223Z","steps":["trace[271376371] 'process raft request' (duration: 206.516006ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:40:44.987361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10529} {"level":"info","ts":"2026-07-08T02:40:45.067479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10529,"took":"77.765958ms","hash":3256208604,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":26480640,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-08T02:40:45.067537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3256208604,"revision":10529,"compact-revision":8164} {"level":"info","ts":"2026-07-08T02:44:46.566687Z","caller":"traceutil/trace.go:171","msg":"trace[902193236] transaction","detail":"{read_only:false; response_revision:14378; number_of_response:1; }","duration":"131.911858ms","start":"2026-07-08T02:44:46.434749Z","end":"2026-07-08T02:44:46.566661Z","steps":["trace[902193236] 'process raft request' (duration: 131.745163ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:44:46.567486Z","caller":"traceutil/trace.go:171","msg":"trace[858112991] linearizableReadLoop","detail":"{readStateIndex:14869; appliedIndex:14867; }","duration":"130.55865ms","start":"2026-07-08T02:44:46.436894Z","end":"2026-07-08T02:44:46.567453Z","steps":["trace[858112991] 'read index received' (duration: 129.691966ms)","trace[858112991] 'applied index is now lower than readState.Index' (duration: 865.934µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:44:46.567695Z","caller":"traceutil/trace.go:171","msg":"trace[1319982331] transaction","detail":"{read_only:false; response_revision:14379; number_of_response:1; }","duration":"131.944739ms","start":"2026-07-08T02:44:46.435717Z","end":"2026-07-08T02:44:46.567661Z","steps":["trace[1319982331] 'process raft request' (duration: 131.61626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:44:46.567978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.4657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-08T02:44:46.568048Z","caller":"traceutil/trace.go:171","msg":"trace[1024734659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14379; }","duration":"124.584253ms","start":"2026-07-08T02:44:46.443449Z","end":"2026-07-08T02:44:46.568033Z","steps":["trace[1024734659] 'agreement among raft nodes before linearized reading' (duration: 124.395458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:44:46.567993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.124078ms","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-08T02:44:46.568092Z","caller":"traceutil/trace.go:171","msg":"trace[1659438154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14379; }","duration":"130.289053ms","start":"2026-07-08T02:44:46.437789Z","end":"2026-07-08T02:44:46.568078Z","steps":["trace[1659438154] 'agreement among raft nodes before linearized reading' (duration: 130.042596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:44:46.568513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.623369ms","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-08T02:44:46.56857Z","caller":"traceutil/trace.go:171","msg":"trace[835148419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14379; }","duration":"131.705332ms","start":"2026-07-08T02:44:46.436854Z","end":"2026-07-08T02:44:46.568559Z","steps":["trace[835148419] 'agreement among raft nodes before linearized reading' (duration: 130.832118ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:45:44.994344Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12739} {"level":"info","ts":"2026-07-08T02:45:45.069176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12739,"took":"72.78637ms","hash":413671954,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":28626944,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-08T02:45:45.069308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":413671954,"revision":12739,"compact-revision":10529} {"level":"info","ts":"2026-07-08T02:47:15.490454Z","caller":"traceutil/trace.go:171","msg":"trace[1274640949] transaction","detail":"{read_only:false; response_revision:15725; number_of_response:1; }","duration":"141.537586ms","start":"2026-07-08T02:47:15.348887Z","end":"2026-07-08T02:47:15.490424Z","steps":["trace[1274640949] 'process raft request' (duration: 128.728388ms)","trace[1274640949] 'compare' (duration: 11.922673ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T02:47:15.513775Z","caller":"traceutil/trace.go:171","msg":"trace[1517204601] transaction","detail":"{read_only:false; response_revision:15726; number_of_response:1; }","duration":"163.303825ms","start":"2026-07-08T02:47:15.350446Z","end":"2026-07-08T02:47:15.51375Z","steps":["trace[1517204601] 'process raft request' (duration: 163.055148ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:15.513797Z","caller":"traceutil/trace.go:171","msg":"trace[1612945032] transaction","detail":"{read_only:false; response_revision:15728; number_of_response:1; }","duration":"162.320716ms","start":"2026-07-08T02:47:15.35145Z","end":"2026-07-08T02:47:15.51377Z","steps":["trace[1612945032] 'process raft request' (duration: 162.237704ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:15.513975Z","caller":"traceutil/trace.go:171","msg":"trace[1604584757] transaction","detail":"{read_only:false; response_revision:15727; number_of_response:1; }","duration":"163.303914ms","start":"2026-07-08T02:47:15.350661Z","end":"2026-07-08T02:47:15.513965Z","steps":["trace[1604584757] 'process raft request' (duration: 162.981915ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:15.514093Z","caller":"traceutil/trace.go:171","msg":"trace[706382149] transaction","detail":"{read_only:false; response_revision:15729; number_of_response:1; }","duration":"108.597265ms","start":"2026-07-08T02:47:15.405482Z","end":"2026-07-08T02:47:15.514079Z","steps":["trace[706382149] 'process raft request' (duration: 108.244325ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:15.514277Z","caller":"traceutil/trace.go:171","msg":"trace[1116182241] linearizableReadLoop","detail":"{readStateIndex:16264; appliedIndex:16260; }","duration":"162.595794ms","start":"2026-07-08T02:47:15.35167Z","end":"2026-07-08T02:47:15.514266Z","steps":["trace[1116182241] 'read index received' (duration: 126.027952ms)","trace[1116182241] 'applied index is now lower than readState.Index' (duration: 36.566922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:47:15.515084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.792644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737243"} {"level":"info","ts":"2026-07-08T02:47:15.515126Z","caller":"traceutil/trace.go:171","msg":"trace[1913594240] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15731; }","duration":"160.875396ms","start":"2026-07-08T02:47:15.354241Z","end":"2026-07-08T02:47:15.515116Z","steps":["trace[1913594240] 'agreement among raft nodes before linearized reading' (duration: 160.107334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:15.515175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.482719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737243"} {"level":"info","ts":"2026-07-08T02:47:15.51527Z","caller":"traceutil/trace.go:171","msg":"trace[649315560] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15731; }","duration":"163.608213ms","start":"2026-07-08T02:47:15.351638Z","end":"2026-07-08T02:47:15.515247Z","steps":["trace[649315560] 'agreement among raft nodes before linearized reading' (duration: 162.693117ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:16.549067Z","caller":"traceutil/trace.go:171","msg":"trace[373780616] linearizableReadLoop","detail":"{readStateIndex:16269; appliedIndex:16268; }","duration":"186.396399ms","start":"2026-07-08T02:47:16.362642Z","end":"2026-07-08T02:47:16.549038Z","steps":["trace[373780616] 'read index received' (duration: 185.32962ms)","trace[373780616] 'applied index is now lower than readState.Index' (duration: 1.065369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T02:47:16.549526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.39723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T02:47:16.549503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.831532ms","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-08T02:47:16.549591Z","caller":"traceutil/trace.go:171","msg":"trace[2016360849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15733; }","duration":"186.501742ms","start":"2026-07-08T02:47:16.363073Z","end":"2026-07-08T02:47:16.549575Z","steps":["trace[2016360849] 'agreement among raft nodes before linearized reading' (duration: 186.368999ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:16.549625Z","caller":"traceutil/trace.go:171","msg":"trace[1792111055] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:15733; }","duration":"187.024607ms","start":"2026-07-08T02:47:16.362571Z","end":"2026-07-08T02:47:16.549596Z","steps":["trace[1792111055] 'agreement among raft nodes before linearized reading' (duration: 186.738079ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T02:47:16.549905Z","caller":"traceutil/trace.go:171","msg":"trace[1730020157] transaction","detail":"{read_only:false; response_revision:15733; number_of_response:1; }","duration":"311.230799ms","start":"2026-07-08T02:47:16.238646Z","end":"2026-07-08T02:47:16.549876Z","steps":["trace[1730020157] 'process raft request' (duration: 309.364967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T02:47:16.550054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T02:47:16.238623Z","time spent":"311.339222ms","remote":"127.0.0.1:43360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416621,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T02:50:45.001289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14894} {"level":"info","ts":"2026-07-08T02:50:45.097992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14894,"took":"93.74102ms","hash":3832375658,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":30580736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T02:50:45.098067Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3832375658,"revision":14894,"compact-revision":12739} {"level":"info","ts":"2026-07-08T02:55:45.008422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17373} {"level":"info","ts":"2026-07-08T02:55:45.100855Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17373,"took":"89.571415ms","hash":3305626806,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":29478912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T02:55:45.100952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3305626806,"revision":17373,"compact-revision":14894} {"level":"info","ts":"2026-07-08T02:56:22.123249Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T02:56:22.128099Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-08T02:56:22.1282Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-08T03:00:45.015504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19142} {"level":"info","ts":"2026-07-08T03:00:45.098568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19142,"took":"79.761424ms","hash":3906975419,"current-db-size-bytes":58617856,"current-db-size":"59 MB","current-db-size-in-use-bytes":24981504,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-08T03:00:45.098635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3906975419,"revision":19142,"compact-revision":17373} {"level":"warn","ts":"2026-07-08T03:00:47.398464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514686442568835,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T03:00:47.803022Z","caller":"traceutil/trace.go:171","msg":"trace[1208926551] transaction","detail":"{read_only:false; response_revision:20740; number_of_response:1; }","duration":"912.636547ms","start":"2026-07-08T03:00:46.890359Z","end":"2026-07-08T03:00:47.802996Z","steps":["trace[1208926551] 'process raft request' (duration: 912.104542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.803287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.890339Z","time spent":"912.754159ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:00:47.808276Z","caller":"traceutil/trace.go:171","msg":"trace[1826590819] transaction","detail":"{read_only:false; response_revision:20742; number_of_response:1; }","duration":"917.692417ms","start":"2026-07-08T03:00:46.890552Z","end":"2026-07-08T03:00:47.808244Z","steps":["trace[1826590819] 'process raft request' (duration: 917.587384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.80846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.890536Z","time spent":"917.832452ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:00:47.808581Z","caller":"traceutil/trace.go:171","msg":"trace[1487177982] linearizableReadLoop","detail":"{readStateIndex:21464; appliedIndex:21460; }","duration":"910.683542ms","start":"2026-07-08T03:00:46.897868Z","end":"2026-07-08T03:00:47.808552Z","steps":["trace[1487177982] 'read index received' (duration: 904.741616ms)","trace[1487177982] 'applied index is now lower than readState.Index' (duration: 5.940606ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:00:47.808738Z","caller":"traceutil/trace.go:171","msg":"trace[374829200] transaction","detail":"{read_only:false; response_revision:20741; number_of_response:1; }","duration":"918.359886ms","start":"2026-07-08T03:00:46.890362Z","end":"2026-07-08T03:00:47.808722Z","steps":["trace[374829200] 'process raft request' (duration: 917.616536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.808806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.890337Z","time spent":"918.434168ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T03:00:47.808903Z","caller":"traceutil/trace.go:171","msg":"trace[1115557475] transaction","detail":"{read_only:false; response_revision:20743; number_of_response:1; }","duration":"918.296894ms","start":"2026-07-08T03:00:46.890587Z","end":"2026-07-08T03:00:47.808884Z","steps":["trace[1115557475] 'process raft request' (duration: 917.610145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.890575Z","time spent":"918.385937ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:00:47.809146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.280869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-nova-server\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-07-08T03:00:47.809207Z","caller":"traceutil/trace.go:171","msg":"trace[273921964] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-nova-server; range_end:; response_count:1; response_revision:20743; }","duration":"911.397882ms","start":"2026-07-08T03:00:46.897794Z","end":"2026-07-08T03:00:47.809192Z","steps":["trace[273921964] 'agreement among raft nodes before linearized reading' (duration: 911.225067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.888541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T03:00:47.809256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.897781Z","time spent":"911.463644ms","remote":"127.0.0.1:44244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":721,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-nova-server\" "} {"level":"info","ts":"2026-07-08T03:00:47.809287Z","caller":"traceutil/trace.go:171","msg":"trace[747752393] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:20743; }","duration":"734.976673ms","start":"2026-07-08T03:00:47.074292Z","end":"2026-07-08T03:00:47.809269Z","steps":["trace[747752393] 'agreement among raft nodes before linearized reading' (duration: 734.858699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.027919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jzlpq\" ","response":"range_response_count:1 size:20641"} {"level":"warn","ts":"2026-07-08T03:00:47.809321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.074286Z","time spent":"735.027904ms","remote":"127.0.0.1:43062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-08T03:00:47.809356Z","caller":"traceutil/trace.go:171","msg":"trace[51498273] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-jzlpq; range_end:; response_count:1; response_revision:20743; }","duration":"563.151062ms","start":"2026-07-08T03:00:47.246186Z","end":"2026-07-08T03:00:47.809337Z","steps":["trace[51498273] 'agreement among raft nodes before linearized reading' (duration: 562.915145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.246175Z","time spent":"563.221184ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20664,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-jzlpq\" "} {"level":"warn","ts":"2026-07-08T03:00:47.809406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.873094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-8l2dl\" ","response":"range_response_count:1 size:14274"} {"level":"warn","ts":"2026-07-08T03:00:47.809568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.349194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-07-08T03:00:47.809598Z","caller":"traceutil/trace.go:171","msg":"trace[475832206] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20743; }","duration":"659.413046ms","start":"2026-07-08T03:00:47.150178Z","end":"2026-07-08T03:00:47.809591Z","steps":["trace[475832206] 'agreement among raft nodes before linearized reading' (duration: 659.301673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.150158Z","time spent":"659.454977ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-07-08T03:00:47.809577Z","caller":"traceutil/trace.go:171","msg":"trace[655571515] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-8l2dl; range_end:; response_count:1; response_revision:20743; }","duration":"875.069309ms","start":"2026-07-08T03:00:46.934435Z","end":"2026-07-08T03:00:47.809504Z","steps":["trace[655571515] 'agreement among raft nodes before linearized reading' (duration: 874.592096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.382999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-07-08T03:00:47.809805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.333603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-kghfq\" ","response":"range_response_count:1 size:6528"} {"level":"info","ts":"2026-07-08T03:00:47.809862Z","caller":"traceutil/trace.go:171","msg":"trace[913818199] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20743; }","duration":"572.514373ms","start":"2026-07-08T03:00:47.237279Z","end":"2026-07-08T03:00:47.809793Z","steps":["trace[913818199] 'agreement among raft nodes before linearized reading' (duration: 572.356939ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:00:47.809907Z","caller":"traceutil/trace.go:171","msg":"trace[1295457193] range","detail":"{range_begin:/registry/pods/openstack/keepalived-kghfq; range_end:; response_count:1; response_revision:20743; }","duration":"872.455706ms","start":"2026-07-08T03:00:46.937434Z","end":"2026-07-08T03:00:47.80989Z","steps":["trace[1295457193] 'agreement among raft nodes before linearized reading' (duration: 872.22487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.136029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-l3-agent-default-spqrw\" ","response":"range_response_count:1 size:18668"} {"level":"info","ts":"2026-07-08T03:00:47.809969Z","caller":"traceutil/trace.go:171","msg":"trace[1355564478] range","detail":"{range_begin:/registry/pods/openstack/neutron-l3-agent-default-spqrw; range_end:; response_count:1; response_revision:20743; }","duration":"720.264183ms","start":"2026-07-08T03:00:47.089695Z","end":"2026-07-08T03:00:47.80996Z","steps":["trace[1355564478] 'agreement among raft nodes before linearized reading' (duration: 720.104849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.809967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.937423Z","time spent":"872.531018ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-kghfq\" "} {"level":"warn","ts":"2026-07-08T03:00:47.810017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.08967Z","time spent":"720.320824ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":18691,"request content":"key:\"/registry/pods/openstack/neutron-l3-agent-default-spqrw\" "} {"level":"warn","ts":"2026-07-08T03:00:47.809913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.237268Z","time spent":"572.631876ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-08T03:00:47.810129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.431138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-08T03:00:47.80971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.93442Z","time spent":"875.269884ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14297,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-8l2dl\" "} {"level":"warn","ts":"2026-07-08T03:00:47.810271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.920997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pk5gr\" ","response":"range_response_count:1 size:22096"} {"level":"info","ts":"2026-07-08T03:00:47.810314Z","caller":"traceutil/trace.go:171","msg":"trace[421601837] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pk5gr; range_end:; response_count:1; response_revision:20743; }","duration":"481.977858ms","start":"2026-07-08T03:00:47.328323Z","end":"2026-07-08T03:00:47.810301Z","steps":["trace[421601837] 'agreement among raft nodes before linearized reading' (duration: 481.885496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.810343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.328311Z","time spent":"482.02529ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22119,"request content":"key:\"/registry/pods/openstack/nova-compute-default-pk5gr\" "} {"level":"warn","ts":"2026-07-08T03:00:47.810341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.757326ms","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-08T03:00:47.810376Z","caller":"traceutil/trace.go:171","msg":"trace[933467663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20743; }","duration":"517.800287ms","start":"2026-07-08T03:00:47.292568Z","end":"2026-07-08T03:00:47.810368Z","steps":["trace[933467663] 'agreement among raft nodes before linearized reading' (duration: 517.723145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.810272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.729351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-08T03:00:47.810412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.292562Z","time spent":"517.843128ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T03:00:47.810432Z","caller":"traceutil/trace.go:171","msg":"trace[1339012126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20743; }","duration":"491.908986ms","start":"2026-07-08T03:00:47.318515Z","end":"2026-07-08T03:00:47.810424Z","steps":["trace[1339012126] 'agreement among raft nodes before linearized reading' (duration: 491.70177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.810458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.318505Z","time spent":"491.946477ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T03:00:47.81019Z","caller":"traceutil/trace.go:171","msg":"trace[578264929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20743; }","duration":"526.50878ms","start":"2026-07-08T03:00:47.283664Z","end":"2026-07-08T03:00:47.810172Z","steps":["trace[578264929] 'agreement among raft nodes before linearized reading' (duration: 526.349775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.810524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.283656Z","time spent":"526.862411ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T03:00:47.810548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.809736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-07-08T03:00:47.810176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.347921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-08T03:00:47.810537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.720528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-9zwlc\" ","response":"range_response_count:1 size:18642"} {"level":"info","ts":"2026-07-08T03:00:47.810579Z","caller":"traceutil/trace.go:171","msg":"trace[418474920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20743; }","duration":"755.766632ms","start":"2026-07-08T03:00:47.054805Z","end":"2026-07-08T03:00:47.810572Z","steps":["trace[418474920] 'agreement among raft nodes before linearized reading' (duration: 755.292919ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:00:47.810578Z","caller":"traceutil/trace.go:171","msg":"trace[724912756] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20743; }","duration":"532.859248ms","start":"2026-07-08T03:00:47.277709Z","end":"2026-07-08T03:00:47.810568Z","steps":["trace[724912756] 'agreement among raft nodes before linearized reading' (duration: 532.782706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.810608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.054799Z","time spent":"755.802954ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T03:00:47.810598Z","caller":"traceutil/trace.go:171","msg":"trace[617742249] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-9zwlc; range_end:; response_count:1; response_revision:20743; }","duration":"563.79062ms","start":"2026-07-08T03:00:47.246791Z","end":"2026-07-08T03:00:47.810582Z","steps":["trace[617742249] 'agreement among raft nodes before linearized reading' (duration: 563.648536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.810612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.277698Z","time spent":"532.908379ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-08T03:00:47.810644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.246786Z","time spent":"563.846922ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18665,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-9zwlc\" "} {"level":"warn","ts":"2026-07-08T03:00:47.810887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.716048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T03:00:47.810933Z","caller":"traceutil/trace.go:171","msg":"trace[1295416994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20743; }","duration":"450.779849ms","start":"2026-07-08T03:00:47.360141Z","end":"2026-07-08T03:00:47.810921Z","steps":["trace[1295416994] 'agreement among raft nodes before linearized reading' (duration: 449.656287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.810923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.794084ms","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":"warn","ts":"2026-07-08T03:00:47.810962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.36013Z","time spent":"450.82535ms","remote":"127.0.0.1:44028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-08T03:00:47.810984Z","caller":"traceutil/trace.go:171","msg":"trace[1711103055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20743; }","duration":"476.875326ms","start":"2026-07-08T03:00:47.33409Z","end":"2026-07-08T03:00:47.810966Z","steps":["trace[1711103055] 'agreement among raft nodes before linearized reading' (duration: 476.31392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.811041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.33408Z","time spent":"476.948569ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T03:00:47.811941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.54169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pk5gr\" ","response":"range_response_count:1 size:22096"} {"level":"warn","ts":"2026-07-08T03:00:47.811977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.307189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:387639"} {"level":"info","ts":"2026-07-08T03:00:47.811998Z","caller":"traceutil/trace.go:171","msg":"trace[1906653016] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pk5gr; range_end:; response_count:1; response_revision:20743; }","duration":"876.716804ms","start":"2026-07-08T03:00:46.935267Z","end":"2026-07-08T03:00:47.811984Z","steps":["trace[1906653016] 'agreement among raft nodes before linearized reading' (duration: 874.520633ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:00:47.812015Z","caller":"traceutil/trace.go:171","msg":"trace[1762394536] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:20743; }","duration":"812.35069ms","start":"2026-07-08T03:00:46.999655Z","end":"2026-07-08T03:00:47.812006Z","steps":["trace[1762394536] 'agreement among raft nodes before linearized reading' (duration: 809.667665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.812042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.935263Z","time spent":"876.767597ms","remote":"127.0.0.1:44226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22119,"request content":"key:\"/registry/pods/openstack/nova-compute-default-pk5gr\" "} {"level":"warn","ts":"2026-07-08T03:00:47.812067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:46.999642Z","time spent":"812.401392ms","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1309,"response size":387662,"request content":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 "} {"level":"warn","ts":"2026-07-08T03:00:47.811956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.873071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-07-08T03:00:47.812276Z","caller":"traceutil/trace.go:171","msg":"trace[439394206] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:20743; }","duration":"602.231212ms","start":"2026-07-08T03:00:47.210025Z","end":"2026-07-08T03:00:47.812256Z","steps":["trace[439394206] 'agreement among raft nodes before linearized reading' (duration: 599.211778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:00:47.812446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:00:47.210008Z","time spent":"602.425157ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":726,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "}