{"level":"warn","ts":"2026-07-08T03:35:47.478221Z","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-08T03:35:47.478306Z","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-08T03:35:47.478368Z","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-08T03:35:47.478381Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-08T03:35:47.4784Z","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-08T03:35:47.479017Z","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-08T03:35:47.479096Z","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-08T03:35:47.482556Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.281222ms"} {"level":"info","ts":"2026-07-08T03:35:47.490669Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-08T03:35:47.490842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T03:35:47.490879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-08T03:35:47.490899Z","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-08T03:35:47.490914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-08T03:35:47.490972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-08T03:35:47.496909Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T03:35:47.499369Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T03:35:47.501537Z","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-08T03:35:47.504284Z","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-08T03:35:47.504857Z","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-08T03:35:47.504984Z","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-08T03:35:47.505055Z","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-08T03:35:47.505068Z","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-08T03:35:47.50609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-08T03:35:47.508087Z","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-08T03:35:47.510345Z","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-08T03:35:47.510486Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-08T03:35:47.510528Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-08T03:35:47.510852Z","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-08T03:35:47.510916Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T03:35:47.891938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T03:35:47.891992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T03:35:47.892038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-08T03:35:47.892055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-08T03:35:47.892066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-08T03:35:47.892079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-08T03:35:47.892092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-08T03:35:47.893363Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T03:35:47.894867Z","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-08T03:35:47.894972Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T03:35:47.895011Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T03:35:47.895059Z","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-08T03:35:47.895332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T03:35:47.895784Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T03:35:47.895972Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T03:35:47.896046Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T03:35:47.897937Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T03:35:47.899851Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"warn","ts":"2026-07-08T03:36:04.887046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.696009ms","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-08T03:36:04.887191Z","caller":"traceutil/trace.go:171","msg":"trace[593084820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"152.889564ms","start":"2026-07-08T03:36:04.734272Z","end":"2026-07-08T03:36:04.887161Z","steps":["trace[593084820] 'range keys from in-memory index tree' (duration: 152.580656ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:36:05.003999Z","caller":"traceutil/trace.go:171","msg":"trace[155481417] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"113.182507ms","start":"2026-07-08T03:36:04.890798Z","end":"2026-07-08T03:36:05.003981Z","steps":["trace[155481417] 'process raft request' (duration: 113.074094ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:36:27.944505Z","caller":"traceutil/trace.go:171","msg":"trace[549229107] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"140.546907ms","start":"2026-07-08T03:36:27.803914Z","end":"2026-07-08T03:36:27.944461Z","steps":["trace[549229107] 'process raft request' (duration: 140.357842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:36:30.968917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.672945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:36:30.968997Z","caller":"traceutil/trace.go:171","msg":"trace[2104509717] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"144.583657ms","start":"2026-07-08T03:36:30.824404Z","end":"2026-07-08T03:36:30.968987Z","steps":["trace[2104509717] 'compare' (duration: 134.598144ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:36:31.009603Z","caller":"traceutil/trace.go:171","msg":"trace[636398238] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"136.138546ms","start":"2026-07-08T03:36:30.873445Z","end":"2026-07-08T03:36:31.009583Z","steps":["trace[636398238] 'process raft request' (duration: 135.844078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:36:31.502666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.850086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-08T03:36:31.50274Z","caller":"traceutil/trace.go:171","msg":"trace[1424817202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"364.00089ms","start":"2026-07-08T03:36:31.138722Z","end":"2026-07-08T03:36:31.502723Z","steps":["trace[1424817202] 'range keys from in-memory index tree' (duration: 363.688572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:36:31.50279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:36:31.138703Z","time spent":"364.079383ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T03:36:31.534558Z","caller":"traceutil/trace.go:171","msg":"trace[1756987051] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"103.854552ms","start":"2026-07-08T03:36:31.430639Z","end":"2026-07-08T03:36:31.534494Z","steps":["trace[1756987051] 'process raft request' (duration: 99.473832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:37:58.266079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.933882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:37:58.266219Z","caller":"traceutil/trace.go:171","msg":"trace[618492424] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1857; }","duration":"216.121097ms","start":"2026-07-08T03:37:58.050062Z","end":"2026-07-08T03:37:58.266183Z","steps":["trace[618492424] 'count revisions from in-memory index tree' (duration: 215.838738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:37:58.266252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.390465ms","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-08T03:37:58.266363Z","caller":"traceutil/trace.go:171","msg":"trace[176748339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"277.532249ms","start":"2026-07-08T03:37:57.988814Z","end":"2026-07-08T03:37:58.266346Z","steps":["trace[176748339] 'range keys from in-memory index tree' (duration: 277.236441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:37:58.26636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.784239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0d5e3bf5-b8a0-4b5e-af78-c03e3a7a39c2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-08T03:37:58.266448Z","caller":"traceutil/trace.go:171","msg":"trace[1523168805] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0d5e3bf5-b8a0-4b5e-af78-c03e3a7a39c2; range_end:; response_count:1; response_revision:1857; }","duration":"243.918272ms","start":"2026-07-08T03:37:58.022508Z","end":"2026-07-08T03:37:58.266427Z","steps":["trace[1523168805] 'range keys from in-memory index tree' (duration: 243.576433ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:37:58.307694Z","caller":"traceutil/trace.go:171","msg":"trace[1289287208] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"127.065442ms","start":"2026-07-08T03:37:58.180604Z","end":"2026-07-08T03:37:58.307669Z","steps":["trace[1289287208] 'process raft request' (duration: 126.897098ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:37:58.315896Z","caller":"traceutil/trace.go:171","msg":"trace[831490740] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"132.902163ms","start":"2026-07-08T03:37:58.182978Z","end":"2026-07-08T03:37:58.31588Z","steps":["trace[831490740] 'process raft request' (duration: 132.611455ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:37:58.537698Z","caller":"traceutil/trace.go:171","msg":"trace[1805925973] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"109.357993ms","start":"2026-07-08T03:37:58.428314Z","end":"2026-07-08T03:37:58.537672Z","steps":["trace[1805925973] 'process raft request' (duration: 109.180288ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:37:58.563626Z","caller":"traceutil/trace.go:171","msg":"trace[195929536] linearizableReadLoop","detail":"{readStateIndex:1937; appliedIndex:1935; }","duration":"114.625679ms","start":"2026-07-08T03:37:58.448972Z","end":"2026-07-08T03:37:58.563597Z","steps":["trace[195929536] 'read index received' (duration: 88.52508ms)","trace[195929536] 'applied index is now lower than readState.Index' (duration: 26.099579ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:37:58.563729Z","caller":"traceutil/trace.go:171","msg":"trace[1416311781] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"125.818998ms","start":"2026-07-08T03:37:58.43787Z","end":"2026-07-08T03:37:58.563689Z","steps":["trace[1416311781] 'process raft request' (duration: 125.608482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:37:58.563757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.771964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:37:58.563805Z","caller":"traceutil/trace.go:171","msg":"trace[17653376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1863; }","duration":"114.865226ms","start":"2026-07-08T03:37:58.448927Z","end":"2026-07-08T03:37:58.563792Z","steps":["trace[17653376] 'agreement among raft nodes before linearized reading' (duration: 114.773853ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:38:47.790335Z","caller":"traceutil/trace.go:171","msg":"trace[2100348405] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"131.790914ms","start":"2026-07-08T03:38:47.658515Z","end":"2026-07-08T03:38:47.790306Z","steps":["trace[2100348405] 'read index received' (duration: 131.513796ms)","trace[2100348405] 'applied index is now lower than readState.Index' (duration: 275.968µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:38:47.7909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.3613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-08T03:38:47.790877Z","caller":"traceutil/trace.go:171","msg":"trace[478547167] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"154.318405ms","start":"2026-07-08T03:38:47.63634Z","end":"2026-07-08T03:38:47.790658Z","steps":["trace[478547167] 'process raft request' (duration: 153.78958ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:38:47.790956Z","caller":"traceutil/trace.go:171","msg":"trace[673416844] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2178; }","duration":"132.448631ms","start":"2026-07-08T03:38:47.658491Z","end":"2026-07-08T03:38:47.790939Z","steps":["trace[673416844] 'agreement among raft nodes before linearized reading' (duration: 132.160144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:38:48.737015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.372347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:38:48.737115Z","caller":"traceutil/trace.go:171","msg":"trace[1977849744] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2263; }","duration":"196.787416ms","start":"2026-07-08T03:38:48.540309Z","end":"2026-07-08T03:38:48.737096Z","steps":["trace[1977849744] 'read index received' (duration: 30.273735ms)","trace[1977849744] 'applied index is now lower than readState.Index' (duration: 166.512781ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:38:48.737226Z","caller":"traceutil/trace.go:171","msg":"trace[1286355118] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"235.218236ms","start":"2026-07-08T03:38:48.501973Z","end":"2026-07-08T03:38:48.737191Z","steps":["trace[1286355118] 'process raft request' (duration: 68.566751ms)","trace[1286355118] 'compare' (duration: 166.278205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:38:48.737319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.009352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-08T03:38:48.737402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.260475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T03:38:48.737466Z","caller":"traceutil/trace.go:171","msg":"trace[2038175237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2180; }","duration":"178.343857ms","start":"2026-07-08T03:38:48.559103Z","end":"2026-07-08T03:38:48.737447Z","steps":["trace[2038175237] 'agreement among raft nodes before linearized reading' (duration: 178.142262ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:38:48.737414Z","caller":"traceutil/trace.go:171","msg":"trace[260460087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2180; }","duration":"197.137546ms","start":"2026-07-08T03:38:48.54026Z","end":"2026-07-08T03:38:48.737398Z","steps":["trace[260460087] 'agreement among raft nodes before linearized reading' (duration: 196.94489ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:40:02.926868Z","caller":"traceutil/trace.go:171","msg":"trace[1273980288] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"103.015352ms","start":"2026-07-08T03:40:02.823823Z","end":"2026-07-08T03:40:02.926839Z","steps":["trace[1273980288] 'process raft request' (duration: 102.831097ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:43:53.531631Z","caller":"traceutil/trace.go:171","msg":"trace[1042648421] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"199.702499ms","start":"2026-07-08T03:43:53.331895Z","end":"2026-07-08T03:43:53.531597Z","steps":["trace[1042648421] 'process raft request' (duration: 173.789798ms)","trace[1042648421] 'compare' (duration: 25.736476ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:43:53.531617Z","caller":"traceutil/trace.go:171","msg":"trace[372950994] linearizableReadLoop","detail":"{readStateIndex:5063; appliedIndex:5062; }","duration":"182.927272ms","start":"2026-07-08T03:43:53.348661Z","end":"2026-07-08T03:43:53.531588Z","steps":["trace[372950994] 'read index received' (duration: 157.053242ms)","trace[372950994] 'applied index is now lower than readState.Index' (duration: 25.87268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:43:53.531908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.2134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abf84349-8159-4674-94de-23ede5d0cbbc\" ","response":"range_response_count:1 size:4063"} {"level":"info","ts":"2026-07-08T03:43:53.531962Z","caller":"traceutil/trace.go:171","msg":"trace[154882706] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abf84349-8159-4674-94de-23ede5d0cbbc; range_end:; response_count:1; response_revision:4878; }","duration":"183.319633ms","start":"2026-07-08T03:43:53.348625Z","end":"2026-07-08T03:43:53.531944Z","steps":["trace[154882706] 'agreement among raft nodes before linearized reading' (duration: 183.066656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:43:53.587289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.397676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T03:43:53.587388Z","caller":"traceutil/trace.go:171","msg":"trace[21834750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4879; }","duration":"206.53205ms","start":"2026-07-08T03:43:53.380832Z","end":"2026-07-08T03:43:53.587365Z","steps":["trace[21834750] 'agreement among raft nodes before linearized reading' (duration: 206.284153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:43:53.587916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.396753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:43:53.587979Z","caller":"traceutil/trace.go:171","msg":"trace[239093755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4879; }","duration":"138.498225ms","start":"2026-07-08T03:43:53.449464Z","end":"2026-07-08T03:43:53.587962Z","steps":["trace[239093755] 'agreement among raft nodes before linearized reading' (duration: 138.402393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:44:07.049238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.638319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/memcached-memcached\" ","response":"range_response_count:1 size:543"} {"level":"warn","ts":"2026-07-08T03:44:07.049604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.527291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-rbb5r.18c033a35caf3bd9\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-08T03:44:07.049316Z","caller":"traceutil/trace.go:171","msg":"trace[1373815831] range","detail":"{range_begin:/registry/serviceaccounts/openstack/memcached-memcached; range_end:; response_count:1; response_revision:5159; }","duration":"166.761842ms","start":"2026-07-08T03:44:06.882538Z","end":"2026-07-08T03:44:07.0493Z","steps":["trace[1373815831] 'range keys from in-memory index tree' (duration: 166.496354ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:44:07.049714Z","caller":"traceutil/trace.go:171","msg":"trace[915023812] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-rbb5r.18c033a35caf3bd9; range_end:; response_count:1; response_revision:5159; }","duration":"191.673716ms","start":"2026-07-08T03:44:06.858005Z","end":"2026-07-08T03:44:07.049678Z","steps":["trace[915023812] 'range keys from in-memory index tree' (duration: 191.331206ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:44:15.203894Z","caller":"traceutil/trace.go:171","msg":"trace[1400988471] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"111.794933ms","start":"2026-07-08T03:44:15.092073Z","end":"2026-07-08T03:44:15.203868Z","steps":["trace[1400988471] 'process raft request' (duration: 51.835203ms)","trace[1400988471] 'compare' (duration: 59.096555ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:44:27.758411Z","caller":"traceutil/trace.go:171","msg":"trace[945333088] linearizableReadLoop","detail":"{readStateIndex:5711; appliedIndex:5710; }","duration":"141.89389ms","start":"2026-07-08T03:44:27.616493Z","end":"2026-07-08T03:44:27.758387Z","steps":["trace[945333088] 'read index received' (duration: 141.757566ms)","trace[945333088] 'applied index is now lower than readState.Index' (duration: 114.933µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:44:27.758688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.147117ms","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-08T03:44:27.758732Z","caller":"traceutil/trace.go:171","msg":"trace[617123141] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5508; }","duration":"142.237339ms","start":"2026-07-08T03:44:27.616484Z","end":"2026-07-08T03:44:27.758721Z","steps":["trace[617123141] 'agreement among raft nodes before linearized reading' (duration: 141.999923ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:44:27.758886Z","caller":"traceutil/trace.go:171","msg":"trace[793778561] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"143.108784ms","start":"2026-07-08T03:44:27.615617Z","end":"2026-07-08T03:44:27.758726Z","steps":["trace[793778561] 'process raft request' (duration: 142.60672ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:44:27.878706Z","caller":"traceutil/trace.go:171","msg":"trace[1562365265] linearizableReadLoop","detail":"{readStateIndex:5713; appliedIndex:5711; }","duration":"114.457746ms","start":"2026-07-08T03:44:27.764234Z","end":"2026-07-08T03:44:27.878692Z","steps":["trace[1562365265] 'read index received' (duration: 68.295941ms)","trace[1562365265] 'applied index is now lower than readState.Index' (duration: 46.161145ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:44:27.878789Z","caller":"traceutil/trace.go:171","msg":"trace[468161967] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"114.178069ms","start":"2026-07-08T03:44:27.764602Z","end":"2026-07-08T03:44:27.878781Z","steps":["trace[468161967] 'process raft request' (duration: 114.024594ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:44:27.878784Z","caller":"traceutil/trace.go:171","msg":"trace[966805379] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"114.744314ms","start":"2026-07-08T03:44:27.764012Z","end":"2026-07-08T03:44:27.878757Z","steps":["trace[966805379] 'process raft request' (duration: 114.527708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:44:27.878952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.703523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-59d03fab-8056-4211-a774-ba17c5e303a5\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-08T03:44:27.878907Z","caller":"traceutil/trace.go:171","msg":"trace[1056476555] transaction","detail":"{read_only:false; response_revision:5512; number_of_response:1; }","duration":"113.922511ms","start":"2026-07-08T03:44:27.764943Z","end":"2026-07-08T03:44:27.878865Z","steps":["trace[1056476555] 'process raft request' (duration: 113.717925ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:44:27.878985Z","caller":"traceutil/trace.go:171","msg":"trace[2107165531] range","detail":"{range_begin:/registry/persistentvolumes/pvc-59d03fab-8056-4211-a774-ba17c5e303a5; range_end:; response_count:1; response_revision:5512; }","duration":"114.773415ms","start":"2026-07-08T03:44:27.764203Z","end":"2026-07-08T03:44:27.878976Z","steps":["trace[2107165531] 'agreement among raft nodes before linearized reading' (duration: 114.657192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:44:27.892497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.821452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T03:44:27.892555Z","caller":"traceutil/trace.go:171","msg":"trace[480191857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5515; }","duration":"125.903155ms","start":"2026-07-08T03:44:27.76664Z","end":"2026-07-08T03:44:27.892544Z","steps":["trace[480191857] 'agreement among raft nodes before linearized reading' (duration: 125.782171ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:45:47.921351Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3029} {"level":"info","ts":"2026-07-08T03:45:48.003817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3029,"took":"80.947299ms","hash":4250472824,"current-db-size-bytes":59068416,"current-db-size":"59 MB","current-db-size-in-use-bytes":32280576,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T03:45:48.003904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4250472824,"revision":3029,"compact-revision":-1} {"level":"warn","ts":"2026-07-08T03:50:30.602631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.361175ms","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-08T03:50:30.602716Z","caller":"traceutil/trace.go:171","msg":"trace[1090375360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8228; }","duration":"113.467628ms","start":"2026-07-08T03:50:30.489232Z","end":"2026-07-08T03:50:30.6027Z","steps":["trace[1090375360] 'range keys from in-memory index tree' (duration: 113.18805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:50:31.43467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.987448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:50:31.434848Z","caller":"traceutil/trace.go:171","msg":"trace[1251686987] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"237.785272ms","start":"2026-07-08T03:50:31.197049Z","end":"2026-07-08T03:50:31.434834Z","steps":["trace[1251686987] 'process raft request' (duration: 237.70711ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:50:31.434835Z","caller":"traceutil/trace.go:171","msg":"trace[2019532218] transaction","detail":"{read_only:false; response_revision:8231; number_of_response:1; }","duration":"334.614075ms","start":"2026-07-08T03:50:31.100162Z","end":"2026-07-08T03:50:31.434776Z","steps":["trace[2019532218] 'process raft request' (duration: 31.434711ms)","trace[2019532218] 'compare' (duration: 302.730222ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:50:31.434892Z","caller":"traceutil/trace.go:171","msg":"trace[1726829202] linearizableReadLoop","detail":"{readStateIndex:8522; appliedIndex:8521; }","duration":"334.249424ms","start":"2026-07-08T03:50:31.100612Z","end":"2026-07-08T03:50:31.434861Z","steps":["trace[1726829202] 'read index received' (duration: 30.971768ms)","trace[1726829202] 'applied index is now lower than readState.Index' (duration: 303.275446ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:50:31.435052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:50:31.100141Z","time spent":"334.81864ms","remote":"127.0.0.1:44158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T03:50:31.435102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.465631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-08T03:50:31.435154Z","caller":"traceutil/trace.go:171","msg":"trace[1763425005] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:8232; }","duration":"334.536553ms","start":"2026-07-08T03:50:31.100607Z","end":"2026-07-08T03:50:31.435144Z","steps":["trace[1763425005] 'agreement among raft nodes before linearized reading' (duration: 334.337687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:50:31.435198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T03:50:31.100565Z","time spent":"334.624275ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-07-08T03:50:31.480243Z","caller":"traceutil/trace.go:171","msg":"trace[1726260536] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"279.170566ms","start":"2026-07-08T03:50:31.201045Z","end":"2026-07-08T03:50:31.480215Z","steps":["trace[1726260536] 'process raft request' (duration: 278.970141ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:50:31.480526Z","caller":"traceutil/trace.go:171","msg":"trace[977433763] transaction","detail":"{read_only:false; response_revision:8234; number_of_response:1; }","duration":"276.811171ms","start":"2026-07-08T03:50:31.203658Z","end":"2026-07-08T03:50:31.480469Z","steps":["trace[977433763] 'process raft request' (duration: 276.514843ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:50:31.947619Z","caller":"traceutil/trace.go:171","msg":"trace[1481807856] transaction","detail":"{read_only:false; response_revision:8236; number_of_response:1; }","duration":"152.323752ms","start":"2026-07-08T03:50:31.795264Z","end":"2026-07-08T03:50:31.947588Z","steps":["trace[1481807856] 'process raft request' (duration: 152.114166ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:50:47.928418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6153} {"level":"info","ts":"2026-07-08T03:50:48.023765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6153,"took":"93.13149ms","hash":3471982532,"current-db-size-bytes":59068416,"current-db-size":"59 MB","current-db-size-in-use-bytes":23281664,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-08T03:50:48.023807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3471982532,"revision":6153,"compact-revision":3029} {"level":"info","ts":"2026-07-08T03:51:02.554118Z","caller":"traceutil/trace.go:171","msg":"trace[276891655] linearizableReadLoop","detail":"{readStateIndex:8757; appliedIndex:8756; }","duration":"190.58925ms","start":"2026-07-08T03:51:02.363486Z","end":"2026-07-08T03:51:02.554075Z","steps":["trace[276891655] 'read index received' (duration: 126.803443ms)","trace[276891655] 'applied index is now lower than readState.Index' (duration: 63.784607ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:51:02.554208Z","caller":"traceutil/trace.go:171","msg":"trace[2075105626] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"191.901866ms","start":"2026-07-08T03:51:02.362247Z","end":"2026-07-08T03:51:02.554148Z","steps":["trace[2075105626] 'process raft request' (duration: 128.118279ms)","trace[2075105626] 'compare' (duration: 63.296103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:51:02.554351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.151681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T03:51:02.554399Z","caller":"traceutil/trace.go:171","msg":"trace[343010695] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8457; }","duration":"139.272604ms","start":"2026-07-08T03:51:02.415111Z","end":"2026-07-08T03:51:02.554384Z","steps":["trace[343010695] 'agreement among raft nodes before linearized reading' (duration: 139.155381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:51:02.5544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.550437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8xcmf\" ","response":"range_response_count:1 size:6518"} {"level":"info","ts":"2026-07-08T03:51:02.554472Z","caller":"traceutil/trace.go:171","msg":"trace[670627362] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8xcmf; range_end:; response_count:1; response_revision:8457; }","duration":"114.712671ms","start":"2026-07-08T03:51:02.439746Z","end":"2026-07-08T03:51:02.554459Z","steps":["trace[670627362] 'agreement among raft nodes before linearized reading' (duration: 114.564957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:51:02.554802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.31499ms","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-08T03:51:02.554831Z","caller":"traceutil/trace.go:171","msg":"trace[2044455351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8457; }","duration":"191.395361ms","start":"2026-07-08T03:51:02.36343Z","end":"2026-07-08T03:51:02.554825Z","steps":["trace[2044455351] 'agreement among raft nodes before linearized reading' (duration: 190.766464ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:51:11.774691Z","caller":"traceutil/trace.go:171","msg":"trace[1447848834] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"139.495496ms","start":"2026-07-08T03:51:11.635171Z","end":"2026-07-08T03:51:11.774667Z","steps":["trace[1447848834] 'process raft request' (duration: 111.766567ms)","trace[1447848834] 'compare' (duration: 27.626996ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:51:11.805426Z","caller":"traceutil/trace.go:171","msg":"trace[1613027411] linearizableReadLoop","detail":"{readStateIndex:8853; appliedIndex:8851; }","duration":"151.039172ms","start":"2026-07-08T03:51:11.65436Z","end":"2026-07-08T03:51:11.805399Z","steps":["trace[1613027411] 'read index received' (duration: 92.669605ms)","trace[1613027411] 'applied index is now lower than readState.Index' (duration: 58.368327ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:51:11.805555Z","caller":"traceutil/trace.go:171","msg":"trace[664149775] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"166.72118ms","start":"2026-07-08T03:51:11.638802Z","end":"2026-07-08T03:51:11.805524Z","steps":["trace[664149775] 'process raft request' (duration: 166.451293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:51:11.805702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.332109ms","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-08T03:51:11.805778Z","caller":"traceutil/trace.go:171","msg":"trace[1715849314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8548; }","duration":"151.480074ms","start":"2026-07-08T03:51:11.654283Z","end":"2026-07-08T03:51:11.805764Z","steps":["trace[1715849314] 'agreement among raft nodes before linearized reading' (duration: 151.251437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:51:12.020629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.268263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T03:51:12.02158Z","caller":"traceutil/trace.go:171","msg":"trace[1785516675] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"244.913221ms","start":"2026-07-08T03:51:11.776628Z","end":"2026-07-08T03:51:12.021542Z","steps":["trace[1785516675] 'process raft request' (duration: 120.659901ms)","trace[1785516675] 'compare' (duration: 123.123659ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T03:51:12.021666Z","caller":"traceutil/trace.go:171","msg":"trace[1474617334] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"211.796775ms","start":"2026-07-08T03:51:11.809851Z","end":"2026-07-08T03:51:12.021648Z","steps":["trace[1474617334] 'process raft request' (duration: 211.689392ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:51:12.021953Z","caller":"traceutil/trace.go:171","msg":"trace[1747200185] linearizableReadLoop","detail":"{readStateIndex:8854; appliedIndex:8853; }","duration":"216.410421ms","start":"2026-07-08T03:51:11.805531Z","end":"2026-07-08T03:51:12.021942Z","steps":["trace[1747200185] 'read index received' (duration: 91.72726ms)","trace[1747200185] 'applied index is now lower than readState.Index' (duration: 124.681791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T03:51:12.022183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.74188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-6sssv\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-07-08T03:51:12.02222Z","caller":"traceutil/trace.go:171","msg":"trace[1290602386] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-6sssv; range_end:; response_count:1; response_revision:8550; }","duration":"246.815122ms","start":"2026-07-08T03:51:11.775395Z","end":"2026-07-08T03:51:12.02221Z","steps":["trace[1290602386] 'agreement among raft nodes before linearized reading' (duration: 246.664348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T03:51:12.022264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.17695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-08T03:51:12.022311Z","caller":"traceutil/trace.go:171","msg":"trace[291011320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8550; }","duration":"119.272603ms","start":"2026-07-08T03:51:11.903026Z","end":"2026-07-08T03:51:12.022299Z","steps":["trace[291011320] 'agreement among raft nodes before linearized reading' (duration: 119.114928ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T03:51:36.020759Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ac.wal"} {"level":"info","ts":"2026-07-08T03:53:18.091746Z","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-08T03:53:18.10901Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T03:53:18.109071Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T03:55:47.934846Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8327} {"level":"info","ts":"2026-07-08T03:55:48.015414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8327,"took":"78.719684ms","hash":7685232,"current-db-size-bytes":59068416,"current-db-size":"59 MB","current-db-size-in-use-bytes":26841088,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-08T03:55:48.015468Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":7685232,"revision":8327,"compact-revision":6153} {"level":"info","ts":"2026-07-08T03:56:11.717099Z","caller":"traceutil/trace.go:171","msg":"trace[906159386] transaction","detail":"{read_only:false; response_revision:11145; number_of_response:1; }","duration":"137.962057ms","start":"2026-07-08T03:56:11.579093Z","end":"2026-07-08T03:56:11.717055Z","steps":["trace[906159386] 'process raft request' (duration: 129.489486ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:00:47.941192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11010} {"level":"info","ts":"2026-07-08T04:00:48.018288Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11010,"took":"74.670803ms","hash":2421397417,"current-db-size-bytes":59068416,"current-db-size":"59 MB","current-db-size-in-use-bytes":28491776,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T04:00:48.018335Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2421397417,"revision":11010,"compact-revision":8327} {"level":"info","ts":"2026-07-08T04:02:30.86985Z","caller":"traceutil/trace.go:171","msg":"trace[667659636] transaction","detail":"{read_only:false; response_revision:13685; number_of_response:1; }","duration":"161.269244ms","start":"2026-07-08T04:02:30.708553Z","end":"2026-07-08T04:02:30.869822Z","steps":["trace[667659636] 'process raft request' (duration: 161.026207ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:02:38.101171Z","caller":"traceutil/trace.go:171","msg":"trace[424111196] transaction","detail":"{read_only:false; response_revision:13739; number_of_response:1; }","duration":"102.188626ms","start":"2026-07-08T04:02:37.99894Z","end":"2026-07-08T04:02:38.101128Z","steps":["trace[424111196] 'process raft request' (duration: 102.044802ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:02:38.105963Z","caller":"traceutil/trace.go:171","msg":"trace[1916739297] transaction","detail":"{read_only:false; response_revision:13740; number_of_response:1; }","duration":"105.957049ms","start":"2026-07-08T04:02:37.999979Z","end":"2026-07-08T04:02:38.105936Z","steps":["trace[1916739297] 'process raft request' (duration: 105.796395ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:02:39.080134Z","caller":"traceutil/trace.go:171","msg":"trace[1447358206] linearizableReadLoop","detail":"{readStateIndex:14221; appliedIndex:14220; }","duration":"122.215718ms","start":"2026-07-08T04:02:38.957892Z","end":"2026-07-08T04:02:39.080108Z","steps":["trace[1447358206] 'read index received' (duration: 122.062883ms)","trace[1447358206] 'applied index is now lower than readState.Index' (duration: 151.735µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T04:02:39.080264Z","caller":"traceutil/trace.go:171","msg":"trace[1969042694] transaction","detail":"{read_only:false; response_revision:13747; number_of_response:1; }","duration":"174.439217ms","start":"2026-07-08T04:02:38.905784Z","end":"2026-07-08T04:02:39.080223Z","steps":["trace[1969042694] 'process raft request' (duration: 174.131598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:02:39.080367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.459795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-08T04:02:39.080415Z","caller":"traceutil/trace.go:171","msg":"trace[1727823234] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:13747; }","duration":"122.529737ms","start":"2026-07-08T04:02:38.957872Z","end":"2026-07-08T04:02:39.080402Z","steps":["trace[1727823234] 'agreement among raft nodes before linearized reading' (duration: 122.332721ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:02:47.770434Z","caller":"traceutil/trace.go:171","msg":"trace[632753508] transaction","detail":"{read_only:false; response_revision:13909; number_of_response:1; }","duration":"125.314833ms","start":"2026-07-08T04:02:47.645082Z","end":"2026-07-08T04:02:47.770397Z","steps":["trace[632753508] 'process raft request' (duration: 125.147958ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:04:28.02559Z","caller":"traceutil/trace.go:171","msg":"trace[1212361730] transaction","detail":"{read_only:false; response_revision:14997; number_of_response:1; }","duration":"126.39189ms","start":"2026-07-08T04:04:27.899164Z","end":"2026-07-08T04:04:28.025556Z","steps":["trace[1212361730] 'process raft request' (duration: 126.240285ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:05:47.947918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13044} {"level":"info","ts":"2026-07-08T04:05:48.022137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13044,"took":"71.490384ms","hash":2965830646,"current-db-size-bytes":59068416,"current-db-size":"59 MB","current-db-size-in-use-bytes":31756288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T04:05:48.022202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2965830646,"revision":13044,"compact-revision":11010} {"level":"warn","ts":"2026-07-08T04:10:10.432095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.854964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5509"} {"level":"warn","ts":"2026-07-08T04:10:10.432216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.570695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T04:10:10.432265Z","caller":"traceutil/trace.go:171","msg":"trace[1869851903] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17394; }","duration":"412.628167ms","start":"2026-07-08T04:10:10.019619Z","end":"2026-07-08T04:10:10.432247Z","steps":["trace[1869851903] 'range keys from in-memory index tree' (duration: 412.501783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.019607Z","time spent":"412.698638ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" "} {"level":"warn","ts":"2026-07-08T04:10:10.432316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.664165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T04:10:10.432302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.190302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T04:10:10.43238Z","caller":"traceutil/trace.go:171","msg":"trace[298258521] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17394; }","duration":"341.763437ms","start":"2026-07-08T04:10:10.090599Z","end":"2026-07-08T04:10:10.432363Z","steps":["trace[298258521] 'range keys from in-memory index tree' (duration: 341.539671ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:10:10.432404Z","caller":"traceutil/trace.go:171","msg":"trace[2079547784] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17394; }","duration":"413.364696ms","start":"2026-07-08T04:10:10.019017Z","end":"2026-07-08T04:10:10.432382Z","steps":["trace[2079547784] 'range keys from in-memory index tree' (duration: 413.088319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.284732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-07-08T04:10:10.432457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.018994Z","time spent":"413.452519ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"info","ts":"2026-07-08T04:10:10.432483Z","caller":"traceutil/trace.go:171","msg":"trace[1458785014] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17394; }","duration":"342.401505ms","start":"2026-07-08T04:10:10.090051Z","end":"2026-07-08T04:10:10.432453Z","steps":["trace[1458785014] 'range keys from in-memory index tree' (duration: 342.097046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.090023Z","time spent":"342.519488ms","remote":"127.0.0.1:44072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-08T04:10:10.432562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.506403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-08T04:10:10.432633Z","caller":"traceutil/trace.go:171","msg":"trace[1316784565] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:17394; }","duration":"183.609666ms","start":"2026-07-08T04:10:10.249004Z","end":"2026-07-08T04:10:10.432614Z","steps":["trace[1316784565] 'count revisions from in-memory index tree' (duration: 183.442281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.72735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T04:10:10.432736Z","caller":"traceutil/trace.go:171","msg":"trace[278234212] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17394; }","duration":"416.884824ms","start":"2026-07-08T04:10:10.015814Z","end":"2026-07-08T04:10:10.432699Z","steps":["trace[278234212] 'range keys from in-memory index tree' (duration: 416.621867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.015788Z","time spent":"416.986267ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-07-08T04:10:10.432781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.735266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T04:10:10.432824Z","caller":"traceutil/trace.go:171","msg":"trace[597164347] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17394; }","duration":"341.815678ms","start":"2026-07-08T04:10:10.090998Z","end":"2026-07-08T04:10:10.432813Z","steps":["trace[597164347] 'range keys from in-memory index tree' (duration: 341.644794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.02438ms","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-08T04:10:10.432905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.090978Z","time spent":"341.917392ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"warn","ts":"2026-07-08T04:10:10.432976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.083567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T04:10:10.433113Z","caller":"traceutil/trace.go:171","msg":"trace[117168145] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17394; }","duration":"342.268561ms","start":"2026-07-08T04:10:10.090816Z","end":"2026-07-08T04:10:10.433085Z","steps":["trace[117168145] 'range keys from in-memory index tree' (duration: 341.934402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.433186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.090802Z","time spent":"342.367714ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/neutron-ks-user\" "} {"level":"warn","ts":"2026-07-08T04:10:10.433242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.199392ms","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-08T04:10:10.433291Z","caller":"traceutil/trace.go:171","msg":"trace[1575448927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17394; }","duration":"129.289474ms","start":"2026-07-08T04:10:10.30399Z","end":"2026-07-08T04:10:10.43328Z","steps":["trace[1575448927] 'range keys from in-memory index tree' (duration: 128.609505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.433401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.552624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-txv7l\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-07-08T04:10:10.433472Z","caller":"traceutil/trace.go:171","msg":"trace[1384684637] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-txv7l; range_end:; response_count:1; response_revision:17394; }","duration":"425.709698ms","start":"2026-07-08T04:10:10.007745Z","end":"2026-07-08T04:10:10.433455Z","steps":["trace[1384684637] 'range keys from in-memory index tree' (duration: 424.552956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.433507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.007733Z","time spent":"425.765359ms","remote":"127.0.0.1:44080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22145,"request content":"key:\"/registry/pods/openstack/nova-compute-default-txv7l\" "} {"level":"warn","ts":"2026-07-08T04:10:10.433314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.116512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-08T04:10:10.43292Z","caller":"traceutil/trace.go:171","msg":"trace[1818451526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17394; }","duration":"149.916054ms","start":"2026-07-08T04:10:10.282995Z","end":"2026-07-08T04:10:10.432911Z","steps":["trace[1818451526] 'range keys from in-memory index tree' (duration: 148.847815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.090581Z","time spent":"341.834139ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" "} {"level":"info","ts":"2026-07-08T04:10:10.433699Z","caller":"traceutil/trace.go:171","msg":"trace[443835446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17394; }","duration":"149.38315ms","start":"2026-07-08T04:10:10.284164Z","end":"2026-07-08T04:10:10.433547Z","steps":["trace[443835446] 'range keys from in-memory index tree' (duration: 148.449794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.855188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:1 size:5509"} {"level":"warn","ts":"2026-07-08T04:10:10.432877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.029677ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T04:10:10.433864Z","caller":"traceutil/trace.go:171","msg":"trace[811423680] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17394; }","duration":"412.634716ms","start":"2026-07-08T04:10:10.021187Z","end":"2026-07-08T04:10:10.433821Z","steps":["trace[811423680] 'range keys from in-memory index tree' (duration: 410.681262ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T04:10:10.433907Z","caller":"traceutil/trace.go:171","msg":"trace[411927303] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17394; }","duration":"164.057115ms","start":"2026-07-08T04:10:10.269834Z","end":"2026-07-08T04:10:10.433891Z","steps":["trace[411927303] 'range keys from in-memory index tree' (duration: 163.009276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.43394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.021175Z","time spent":"412.7425ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5532,"request content":"key:\"/registry/jobs/openstack/neutron-db-sync\" "} {"level":"info","ts":"2026-07-08T04:10:10.434303Z","caller":"traceutil/trace.go:171","msg":"trace[1625679638] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:1; response_revision:17394; }","duration":"340.03355ms","start":"2026-07-08T04:10:10.092184Z","end":"2026-07-08T04:10:10.432217Z","steps":["trace[1625679638] 'range keys from in-memory index tree' (duration: 339.629258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.432164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.141186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T04:10:10.434396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:10.092176Z","time spent":"342.20106ms","remote":"127.0.0.1:44140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5532,"request content":"key:\"/registry/jobs/openstack/neutron-db-sync\" "} {"level":"info","ts":"2026-07-08T04:10:10.434417Z","caller":"traceutil/trace.go:171","msg":"trace[1680405853] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:17394; }","duration":"442.4097ms","start":"2026-07-08T04:10:09.991969Z","end":"2026-07-08T04:10:10.434379Z","steps":["trace[1680405853] 'range keys from in-memory index tree' (duration: 440.036684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T04:10:10.434476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T04:10:09.991946Z","time spent":"442.514012ms","remote":"127.0.0.1:44362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-08T04:10:47.954727Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15569} {"level":"info","ts":"2026-07-08T04:10:48.049713Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15569,"took":"91.889597ms","hash":3088834815,"current-db-size-bytes":59068416,"current-db-size":"59 MB","current-db-size-in-use-bytes":29683712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T04:10:48.049785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3088834815,"revision":15569,"compact-revision":13044}