{"level":"warn","ts":"2026-09-01T04:22:09.933675Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T04:22:09.933857Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.85:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.85:2380","--initial-cluster=instance=https://162.253.55.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.85: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-09-01T04:22:09.933999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-01T04:22:09.934024Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-01T04:22:09.934064Z","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-09-01T04:22:09.935446Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"]} {"level":"info","ts":"2026-09-01T04:22:09.935614Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.85: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-09-01T04:22:09.940411Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.407868ms"} {"level":"info","ts":"2026-09-01T04:22:09.947512Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"427150911cb3975a","cluster-id":"f76f6804bd8187de"} {"level":"info","ts":"2026-09-01T04:22:09.947645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T04:22:09.947677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 0"} {"level":"info","ts":"2026-09-01T04:22:09.947713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 427150911cb3975a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T04:22:09.947824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 1"} {"level":"info","ts":"2026-09-01T04:22:09.948484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"warn","ts":"2026-09-01T04:22:09.962111Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T04:22:09.964363Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T04:22:09.966011Z","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-09-01T04:22:09.969297Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"427150911cb3975a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T04:22:09.970153Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"427150911cb3975a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T04:22:09.970305Z","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-09-01T04:22:09.970462Z","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-09-01T04:22:09.970499Z","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-09-01T04:22:09.973841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"info","ts":"2026-09-01T04:22:09.974408Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","added-peer-id":"427150911cb3975a","added-peer-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-01T04:22:09.977747Z","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-09-01T04:22:09.978059Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"427150911cb3975a","initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T04:22:09.978098Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T04:22:09.978293Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-01T04:22:09.978322Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-01T04:22:10.549183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T04:22:10.5493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T04:22:10.54933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgPreVoteResp from 427150911cb3975a at term 1"} {"level":"info","ts":"2026-09-01T04:22:10.549348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became candidate at term 2"} {"level":"info","ts":"2026-09-01T04:22:10.549356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgVoteResp from 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-01T04:22:10.549367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became leader at term 2"} {"level":"info","ts":"2026-09-01T04:22:10.54938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 427150911cb3975a elected leader 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-01T04:22:10.550787Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:22:10.551817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:22:10.551804Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"427150911cb3975a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.85:2379]}","request-path":"/0/members/427150911cb3975a/attributes","cluster-id":"f76f6804bd8187de","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T04:22:10.551856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:22:10.5522Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T04:22:10.552239Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T04:22:10.55232Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:22:10.552435Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:22:10.552492Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:22:10.555623Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T04:22:10.555785Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.85:2379"} {"level":"info","ts":"2026-09-01T04:22:13.076211Z","caller":"traceutil/trace.go:171","msg":"trace[627711815] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"101.689794ms","start":"2026-09-01T04:22:12.974493Z","end":"2026-09-01T04:22:13.076183Z","steps":["trace[627711815] 'read index received' (duration: 101.485468ms)","trace[627711815] 'applied index is now lower than readState.Index' (duration: 203.226µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:22:13.076261Z","caller":"traceutil/trace.go:171","msg":"trace[1077833495] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"102.132886ms","start":"2026-09-01T04:22:12.974084Z","end":"2026-09-01T04:22:13.076217Z","steps":["trace[1077833495] 'process raft request' (duration: 101.944681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:13.076582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.028152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-01T04:22:13.076698Z","caller":"traceutil/trace.go:171","msg":"trace[1250967045] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:72; }","duration":"102.191186ms","start":"2026-09-01T04:22:12.974482Z","end":"2026-09-01T04:22:13.076673Z","steps":["trace[1250967045] 'agreement among raft nodes before linearized reading' (duration: 101.847818ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:28.363159Z","caller":"traceutil/trace.go:171","msg":"trace[831579909] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"159.446395ms","start":"2026-09-01T04:22:28.203649Z","end":"2026-09-01T04:22:28.363095Z","steps":["trace[831579909] 'process raft request' (duration: 159.29631ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:28.960509Z","caller":"traceutil/trace.go:171","msg":"trace[1012809876] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"207.616851ms","start":"2026-09-01T04:22:28.752858Z","end":"2026-09-01T04:22:28.960475Z","steps":["trace[1012809876] 'process raft request' (duration: 207.304802ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:50.166374Z","caller":"traceutil/trace.go:171","msg":"trace[1219033418] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"151.926074ms","start":"2026-09-01T04:22:50.014407Z","end":"2026-09-01T04:22:50.166333Z","steps":["trace[1219033418] 'process raft request' (duration: 146.991112ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:50.166755Z","caller":"traceutil/trace.go:171","msg":"trace[599182910] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"146.761667ms","start":"2026-09-01T04:22:50.019928Z","end":"2026-09-01T04:22:50.16669Z","steps":["trace[599182910] 'process raft request' (duration: 146.347545ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:56.278286Z","caller":"traceutil/trace.go:171","msg":"trace[813265237] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"217.350921ms","start":"2026-09-01T04:22:56.06089Z","end":"2026-09-01T04:22:56.278241Z","steps":["trace[813265237] 'process raft request' (duration: 217.128205ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:56.586783Z","caller":"traceutil/trace.go:171","msg":"trace[206005297] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:653; }","duration":"387.748732ms","start":"2026-09-01T04:22:56.199013Z","end":"2026-09-01T04:22:56.586761Z","steps":["trace[206005297] 'read index received' (duration: 79.136799ms)","trace[206005297] 'applied index is now lower than readState.Index' (duration: 308.611453ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:22:56.586964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.931367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-01T04:22:56.586991Z","caller":"traceutil/trace.go:171","msg":"trace[698959848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"387.976618ms","start":"2026-09-01T04:22:56.199007Z","end":"2026-09-01T04:22:56.586983Z","steps":["trace[698959848] 'agreement among raft nodes before linearized reading' (duration: 387.857915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:56.587016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:56.198941Z","time spent":"388.069621ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-01T04:22:56.586979Z","caller":"traceutil/trace.go:171","msg":"trace[874176555] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"428.56747ms","start":"2026-09-01T04:22:56.158296Z","end":"2026-09-01T04:22:56.586864Z","steps":["trace[874176555] 'process raft request' (duration: 427.425229ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:56.587892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:56.158264Z","time spent":"428.903949ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:22:56.807432Z","caller":"traceutil/trace.go:171","msg":"trace[1870849188] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"216.915501ms","start":"2026-09-01T04:22:56.590462Z","end":"2026-09-01T04:22:56.807377Z","steps":["trace[1870849188] 'process raft request' (duration: 212.243987ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:56.812229Z","caller":"traceutil/trace.go:171","msg":"trace[1439711737] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"221.656407ms","start":"2026-09-01T04:22:56.590556Z","end":"2026-09-01T04:22:56.812213Z","steps":["trace[1439711737] 'process raft request' (duration: 221.345708ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:56.812277Z","caller":"traceutil/trace.go:171","msg":"trace[451756070] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"144.797698ms","start":"2026-09-01T04:22:56.66744Z","end":"2026-09-01T04:22:56.812238Z","steps":["trace[451756070] 'process raft request' (duration: 144.719376ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:22:56.812333Z","caller":"traceutil/trace.go:171","msg":"trace[949286358] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"151.767665ms","start":"2026-09-01T04:22:56.660523Z","end":"2026-09-01T04:22:56.81229Z","steps":["trace[949286358] 'process raft request' (duration: 151.560339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:57.477808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.92048ms","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-09-01T04:22:57.477882Z","caller":"traceutil/trace.go:171","msg":"trace[1913475011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"342.011813ms","start":"2026-09-01T04:22:57.135853Z","end":"2026-09-01T04:22:57.477865Z","steps":["trace[1913475011] 'range keys from in-memory index tree' (duration: 341.707006ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:22:57.477916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:22:57.135785Z","time spent":"342.122046ms","remote":"127.0.0.1:59236","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":"info","ts":"2026-09-01T04:22:57.479328Z","caller":"traceutil/trace.go:171","msg":"trace[1248000006] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"254.258125ms","start":"2026-09-01T04:22:57.225013Z","end":"2026-09-01T04:22:57.479271Z","steps":["trace[1248000006] 'process raft request' (duration: 253.470183ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:23:14.367904Z","caller":"traceutil/trace.go:171","msg":"trace[1707462398] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"212.80861ms","start":"2026-09-01T04:23:14.155062Z","end":"2026-09-01T04:23:14.367871Z","steps":["trace[1707462398] 'read index received' (duration: 181.376863ms)","trace[1707462398] 'applied index is now lower than readState.Index' (duration: 31.430357ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:23:14.367933Z","caller":"traceutil/trace.go:171","msg":"trace[76553542] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"272.910282ms","start":"2026-09-01T04:23:14.094982Z","end":"2026-09-01T04:23:14.367892Z","steps":["trace[76553542] 'process raft request' (duration: 241.590817ms)","trace[76553542] 'compare' (duration: 31.13793ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:23:14.368197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.112058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jw4ll\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-09-01T04:23:14.368255Z","caller":"traceutil/trace.go:171","msg":"trace[1841497342] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jw4ll; range_end:; response_count:1; response_revision:808; }","duration":"213.217701ms","start":"2026-09-01T04:23:14.155021Z","end":"2026-09-01T04:23:14.368239Z","steps":["trace[1841497342] 'agreement among raft nodes before linearized reading' (duration: 212.979895ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:23:45.207891Z","caller":"traceutil/trace.go:171","msg":"trace[988058544] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"135.253653ms","start":"2026-09-01T04:23:45.072602Z","end":"2026-09-01T04:23:45.207855Z","steps":["trace[988058544] 'process raft request' (duration: 132.990423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:35.949665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.452783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"warn","ts":"2026-09-01T04:24:35.949703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.784985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-01T04:24:35.949703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.841349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:24:35.949804Z","caller":"traceutil/trace.go:171","msg":"trace[1257091122] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1891; }","duration":"347.620988ms","start":"2026-09-01T04:24:35.602143Z","end":"2026-09-01T04:24:35.949764Z","steps":["trace[1257091122] 'range keys from in-memory index tree' (duration: 347.213247ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:35.949831Z","caller":"traceutil/trace.go:171","msg":"trace[1952960038] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1891; }","duration":"370.047925ms","start":"2026-09-01T04:24:35.579759Z","end":"2026-09-01T04:24:35.949807Z","steps":["trace[1952960038] 'count revisions from in-memory index tree' (duration: 369.699316ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:35.949831Z","caller":"traceutil/trace.go:171","msg":"trace[1007827058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1891; }","duration":"358.94843ms","start":"2026-09-01T04:24:35.590849Z","end":"2026-09-01T04:24:35.949797Z","steps":["trace[1007827058] 'range keys from in-memory index tree' (duration: 357.827989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:35.949853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.602108Z","time spent":"347.733711ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10690,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-09-01T04:24:35.949893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.579729Z","time spent":"370.146088ms","remote":"127.0.0.1:53636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:24:35.949891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.590834Z","time spent":"359.040312ms","remote":"127.0.0.1:59236","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":"warn","ts":"2026-09-01T04:24:35.949924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.759674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T04:24:35.949969Z","caller":"traceutil/trace.go:171","msg":"trace[1010198709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"355.850077ms","start":"2026-09-01T04:24:35.594107Z","end":"2026-09-01T04:24:35.949957Z","steps":["trace[1010198709] 'range keys from in-memory index tree' (duration: 355.617711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:35.949997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.594088Z","time spent":"355.902868ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T04:24:35.950229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.050528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3a24dffd-f097-4454-8fa8-543462be57fd\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-01T04:24:35.950379Z","caller":"traceutil/trace.go:171","msg":"trace[1123300425] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3a24dffd-f097-4454-8fa8-543462be57fd; range_end:; response_count:1; response_revision:1891; }","duration":"381.208452ms","start":"2026-09-01T04:24:35.569147Z","end":"2026-09-01T04:24:35.950356Z","steps":["trace[1123300425] 'range keys from in-memory index tree' (duration: 380.816472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:35.95047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.569036Z","time spent":"381.418548ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3a24dffd-f097-4454-8fa8-543462be57fd\" "} {"level":"warn","ts":"2026-09-01T04:24:35.950206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.702253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d117c7b0ccfb45\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-01T04:24:35.950639Z","caller":"traceutil/trace.go:171","msg":"trace[732985131] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d117c7b0ccfb45; range_end:; response_count:1; response_revision:1891; }","duration":"350.232517ms","start":"2026-09-01T04:24:35.600346Z","end":"2026-09-01T04:24:35.950578Z","steps":["trace[732985131] 'range keys from in-memory index tree' (duration: 349.172399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:35.950745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.600329Z","time spent":"350.380132ms","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1005,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d117c7b0ccfb45\" "} {"level":"info","ts":"2026-09-01T04:24:35.99124Z","caller":"traceutil/trace.go:171","msg":"trace[1246099507] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"298.995032ms","start":"2026-09-01T04:24:35.692203Z","end":"2026-09-01T04:24:35.991198Z","steps":["trace[1246099507] 'process raft request' (duration: 298.734756ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:35.991653Z","caller":"traceutil/trace.go:171","msg":"trace[528351581] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1969; }","duration":"242.338643ms","start":"2026-09-01T04:24:35.749218Z","end":"2026-09-01T04:24:35.991557Z","steps":["trace[528351581] 'read index received' (duration: 242.249731ms)","trace[528351581] 'applied index is now lower than readState.Index' (duration: 84.962µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:24:35.991883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.645942ms","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-09-01T04:24:35.991976Z","caller":"traceutil/trace.go:171","msg":"trace[486557491] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1892; }","duration":"242.804296ms","start":"2026-09-01T04:24:35.749151Z","end":"2026-09-01T04:24:35.991956Z","steps":["trace[486557491] 'agreement among raft nodes before linearized reading' (duration: 242.556099ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:36.362894Z","caller":"traceutil/trace.go:171","msg":"trace[1137523470] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"409.118965ms","start":"2026-09-01T04:24:35.953751Z","end":"2026-09-01T04:24:36.36287Z","steps":["trace[1137523470] 'process raft request' (duration: 408.864068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:36.363358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.953724Z","time spent":"409.532717ms","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:24:36.363465Z","caller":"traceutil/trace.go:171","msg":"trace[1870188185] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1969; }","duration":"371.592486ms","start":"2026-09-01T04:24:35.991835Z","end":"2026-09-01T04:24:36.363427Z","steps":["trace[1870188185] 'read index received' (duration: 370.793035ms)","trace[1870188185] 'applied index is now lower than readState.Index' (duration: 798.311µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:24:36.363442Z","caller":"traceutil/trace.go:171","msg":"trace[1244629208] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"409.019103ms","start":"2026-09-01T04:24:35.954322Z","end":"2026-09-01T04:24:36.363341Z","steps":["trace[1244629208] 'process raft request' (duration: 408.472978ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:36.363621Z","caller":"traceutil/trace.go:171","msg":"trace[1470927113] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"397.352482ms","start":"2026-09-01T04:24:35.966252Z","end":"2026-09-01T04:24:36.363604Z","steps":["trace[1470927113] 'process raft request' (duration: 397.061795ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:36.363761Z","caller":"traceutil/trace.go:171","msg":"trace[14947797] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"407.830351ms","start":"2026-09-01T04:24:35.955905Z","end":"2026-09-01T04:24:36.363736Z","steps":["trace[14947797] 'process raft request' (duration: 407.192764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:36.36379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.15776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-01T04:24:36.363846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.966219Z","time spent":"397.449895ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:24:36.363853Z","caller":"traceutil/trace.go:171","msg":"trace[2094125781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"485.249992ms","start":"2026-09-01T04:24:35.878583Z","end":"2026-09-01T04:24:36.363833Z","steps":["trace[2094125781] 'agreement among raft nodes before linearized reading' (duration: 485.14989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:36.363876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.95589Z","time spent":"407.928344ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:24:36.363888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.954314Z","time spent":"409.33743ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:24:36.363918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:35.878564Z","time spent":"485.341495ms","remote":"127.0.0.1:58952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T04:24:36.364953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.259549ms","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-09-01T04:24:36.365086Z","caller":"traceutil/trace.go:171","msg":"trace[1716457553] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1897; }","duration":"311.426584ms","start":"2026-09-01T04:24:36.053592Z","end":"2026-09-01T04:24:36.365019Z","steps":["trace[1716457553] 'agreement among raft nodes before linearized reading' (duration: 311.199418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:36.365676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:24:36.053572Z","time spent":"312.079581ms","remote":"127.0.0.1:43028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-01T04:24:36.366289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.002251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-01T04:24:36.366374Z","caller":"traceutil/trace.go:171","msg":"trace[386573298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1897; }","duration":"291.114332ms","start":"2026-09-01T04:24:36.075239Z","end":"2026-09-01T04:24:36.366353Z","steps":["trace[386573298] 'agreement among raft nodes before linearized reading' (duration: 290.771204ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:36.365911Z","caller":"traceutil/trace.go:171","msg":"trace[740137148] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"201.321481ms","start":"2026-09-01T04:24:36.164546Z","end":"2026-09-01T04:24:36.365867Z","steps":["trace[740137148] 'process raft request' (duration: 200.004286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:24:36.366119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.95735ms","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-09-01T04:24:36.366959Z","caller":"traceutil/trace.go:171","msg":"trace[1747990318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1897; }","duration":"288.824362ms","start":"2026-09-01T04:24:36.078092Z","end":"2026-09-01T04:24:36.366916Z","steps":["trace[1747990318] 'agreement among raft nodes before linearized reading' (duration: 287.815735ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:50.658217Z","caller":"traceutil/trace.go:171","msg":"trace[1195159140] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"121.547377ms","start":"2026-09-01T04:24:50.536634Z","end":"2026-09-01T04:24:50.658181Z","steps":["trace[1195159140] 'process raft request' (duration: 121.463454ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:50.658465Z","caller":"traceutil/trace.go:171","msg":"trace[907938221] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"123.301794ms","start":"2026-09-01T04:24:50.5351Z","end":"2026-09-01T04:24:50.658402Z","steps":["trace[907938221] 'process raft request' (duration: 122.907464ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:50.658641Z","caller":"traceutil/trace.go:171","msg":"trace[2125384709] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"123.899158ms","start":"2026-09-01T04:24:50.534721Z","end":"2026-09-01T04:24:50.65862Z","steps":["trace[2125384709] 'process raft request' (duration: 116.07113ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:24:50.658627Z","caller":"traceutil/trace.go:171","msg":"trace[1013097176] transaction","detail":"{read_only:false; response_revision:2023; number_of_response:1; }","duration":"122.515332ms","start":"2026-09-01T04:24:50.536059Z","end":"2026-09-01T04:24:50.658574Z","steps":["trace[1013097176] 'process raft request' (duration: 121.991988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:25:19.731451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.617624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-09-01T04:25:19.731568Z","caller":"traceutil/trace.go:171","msg":"trace[670288755] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2187; }","duration":"144.797198ms","start":"2026-09-01T04:25:19.586748Z","end":"2026-09-01T04:25:19.731545Z","steps":["trace[670288755] 'range keys from in-memory index tree' (duration: 144.396788ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:25:19.731582Z","caller":"traceutil/trace.go:171","msg":"trace[1164878048] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"112.877867ms","start":"2026-09-01T04:25:19.618653Z","end":"2026-09-01T04:25:19.73153Z","steps":["trace[1164878048] 'process raft request' (duration: 87.0675ms)","trace[1164878048] 'compare' (duration: 25.370516ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:25:19.930781Z","caller":"traceutil/trace.go:171","msg":"trace[1043645346] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"125.306419ms","start":"2026-09-01T04:25:19.805424Z","end":"2026-09-01T04:25:19.930731Z","steps":["trace[1043645346] 'process raft request' (duration: 117.288795ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:25:25.218027Z","caller":"traceutil/trace.go:171","msg":"trace[1513885190] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"120.753338ms","start":"2026-09-01T04:25:25.09724Z","end":"2026-09-01T04:25:25.217993Z","steps":["trace[1513885190] 'process raft request' (duration: 76.925599ms)","trace[1513885190] 'compare' (duration: 43.708735ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:25:25.678972Z","caller":"traceutil/trace.go:171","msg":"trace[1446843025] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"140.333889ms","start":"2026-09-01T04:25:25.538603Z","end":"2026-09-01T04:25:25.678937Z","steps":["trace[1446843025] 'process raft request' (duration: 140.110863ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:25:31.327722Z","caller":"traceutil/trace.go:171","msg":"trace[51148080] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"175.884616ms","start":"2026-09-01T04:25:31.151804Z","end":"2026-09-01T04:25:31.327688Z","steps":["trace[51148080] 'process raft request' (duration: 175.66196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:25:31.852113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.715876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:25:31.85226Z","caller":"traceutil/trace.go:171","msg":"trace[527746896] linearizableReadLoop","detail":"{readStateIndex:2340; appliedIndex:2339; }","duration":"341.998233ms","start":"2026-09-01T04:25:31.510237Z","end":"2026-09-01T04:25:31.852235Z","steps":["trace[527746896] 'read index received' (duration: 47.682371ms)","trace[527746896] 'applied index is now lower than readState.Index' (duration: 294.313912ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:25:31.852303Z","caller":"traceutil/trace.go:171","msg":"trace[857435871] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"612.756417ms","start":"2026-09-01T04:25:31.239504Z","end":"2026-09-01T04:25:31.85226Z","steps":["trace[857435871] 'process raft request' (duration: 318.606279ms)","trace[857435871] 'compare' (duration: 293.581242ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:25:31.852554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:25:31.239454Z","time spent":"612.953922ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:25:31.852635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.403993ms","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-09-01T04:25:31.852677Z","caller":"traceutil/trace.go:171","msg":"trace[283580338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2250; }","duration":"342.499037ms","start":"2026-09-01T04:25:31.510167Z","end":"2026-09-01T04:25:31.852666Z","steps":["trace[283580338] 'agreement among raft nodes before linearized reading' (duration: 342.124617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:25:31.852712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:25:31.510114Z","time spent":"342.591209ms","remote":"127.0.0.1:35190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-01T04:25:31.882977Z","caller":"traceutil/trace.go:171","msg":"trace[1022232380] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"179.523223ms","start":"2026-09-01T04:25:31.703434Z","end":"2026-09-01T04:25:31.882957Z","steps":["trace[1022232380] 'process raft request' (duration: 179.40382ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:27:30.855527Z","caller":"traceutil/trace.go:171","msg":"trace[1244052458] transaction","detail":"{read_only:false; response_revision:3076; number_of_response:1; }","duration":"159.202985ms","start":"2026-09-01T04:27:30.696286Z","end":"2026-09-01T04:27:30.855489Z","steps":["trace[1244052458] 'process raft request' (duration: 158.987649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:27:31.314488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.275273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:27:31.315378Z","caller":"traceutil/trace.go:171","msg":"trace[1117181589] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:3076; }","duration":"172.792418ms","start":"2026-09-01T04:27:31.142147Z","end":"2026-09-01T04:27:31.314939Z","steps":["trace[1117181589] 'count revisions from in-memory index tree' (duration: 172.195681ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:30:23.177989Z","caller":"traceutil/trace.go:171","msg":"trace[170365751] transaction","detail":"{read_only:false; response_revision:4132; number_of_response:1; }","duration":"107.497152ms","start":"2026-09-01T04:30:23.070456Z","end":"2026-09-01T04:30:23.177954Z","steps":["trace[170365751] 'process raft request' (duration: 67.272565ms)","trace[170365751] 'compare' (duration: 39.633961ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:30:23.178197Z","caller":"traceutil/trace.go:171","msg":"trace[1770796814] transaction","detail":"{read_only:false; response_revision:4133; number_of_response:1; }","duration":"102.575412ms","start":"2026-09-01T04:30:23.075589Z","end":"2026-09-01T04:30:23.178165Z","steps":["trace[1770796814] 'process raft request' (duration: 102.279574ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:30:55.426569Z","caller":"traceutil/trace.go:171","msg":"trace[934895041] transaction","detail":"{read_only:false; response_revision:4352; number_of_response:1; }","duration":"133.165269ms","start":"2026-09-01T04:30:55.293374Z","end":"2026-09-01T04:30:55.42654Z","steps":["trace[934895041] 'process raft request' (duration: 133.003526ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:30:55.512456Z","caller":"traceutil/trace.go:171","msg":"trace[717593403] transaction","detail":"{read_only:false; response_revision:4353; number_of_response:1; }","duration":"218.500335ms","start":"2026-09-01T04:30:55.293915Z","end":"2026-09-01T04:30:55.512415Z","steps":["trace[717593403] 'process raft request' (duration: 218.258509ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:10.593212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-09-01T04:32:10.705965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"110.913778ms","hash":3328110073,"current-db-size-bytes":45608960,"current-db-size":"46 MB","current-db-size-in-use-bytes":20922368,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-09-01T04:32:10.706192Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3328110073,"revision":2965,"compact-revision":-1} {"level":"info","ts":"2026-09-01T04:32:27.432178Z","caller":"traceutil/trace.go:171","msg":"trace[2031309645] linearizableReadLoop","detail":"{readStateIndex:5649; appliedIndex:5647; }","duration":"113.798634ms","start":"2026-09-01T04:32:27.318316Z","end":"2026-09-01T04:32:27.432115Z","steps":["trace[2031309645] 'read index received' (duration: 43.448938ms)","trace[2031309645] 'applied index is now lower than readState.Index' (duration: 70.348266ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:32:27.43252Z","caller":"traceutil/trace.go:171","msg":"trace[1500014356] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"114.424101ms","start":"2026-09-01T04:32:27.317875Z","end":"2026-09-01T04:32:27.432299Z","steps":["trace[1500014356] 'process raft request' (duration: 107.574488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:27.433386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.034747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210289"} {"level":"info","ts":"2026-09-01T04:32:27.433441Z","caller":"traceutil/trace.go:171","msg":"trace[966864236] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5437; }","duration":"115.132639ms","start":"2026-09-01T04:32:27.318295Z","end":"2026-09-01T04:32:27.433427Z","steps":["trace[966864236] 'agreement among raft nodes before linearized reading' (duration: 114.03434ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:27.636993Z","caller":"traceutil/trace.go:171","msg":"trace[141831803] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"103.015576ms","start":"2026-09-01T04:32:27.533917Z","end":"2026-09-01T04:32:27.636932Z","steps":["trace[141831803] 'process raft request' (duration: 102.670647ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:28.344947Z","caller":"traceutil/trace.go:171","msg":"trace[906075381] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"113.876256ms","start":"2026-09-01T04:32:28.231048Z","end":"2026-09-01T04:32:28.344924Z","steps":["trace[906075381] 'process raft request' (duration: 113.721842ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:31.758095Z","caller":"traceutil/trace.go:171","msg":"trace[1842139279] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5788; }","duration":"189.513362ms","start":"2026-09-01T04:32:31.568539Z","end":"2026-09-01T04:32:31.758053Z","steps":["trace[1842139279] 'read index received' (duration: 189.357358ms)","trace[1842139279] 'applied index is now lower than readState.Index' (duration: 154.804µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:32:31.758277Z","caller":"traceutil/trace.go:171","msg":"trace[912813300] transaction","detail":"{read_only:false; response_revision:5573; number_of_response:1; }","duration":"190.413196ms","start":"2026-09-01T04:32:31.5678Z","end":"2026-09-01T04:32:31.758214Z","steps":["trace[912813300] 'process raft request' (duration: 190.026046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:31.758395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.827801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:32:31.758455Z","caller":"traceutil/trace.go:171","msg":"trace[419093240] range","detail":"{range_begin:/registry/clusterroles/vector; range_end:; response_count:0; response_revision:5573; }","duration":"189.923963ms","start":"2026-09-01T04:32:31.568516Z","end":"2026-09-01T04:32:31.75844Z","steps":["trace[419093240] 'agreement among raft nodes before linearized reading' (duration: 189.78936ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:31.778021Z","caller":"traceutil/trace.go:171","msg":"trace[914613051] transaction","detail":"{read_only:false; response_revision:5574; number_of_response:1; }","duration":"208.90724ms","start":"2026-09-01T04:32:31.569077Z","end":"2026-09-01T04:32:31.777984Z","steps":["trace[914613051] 'process raft request' (duration: 198.746839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:31.778637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.567761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-kl2ds-tls\" ","response":"range_response_count:1 size:4431"} {"level":"info","ts":"2026-09-01T04:32:31.778699Z","caller":"traceutil/trace.go:171","msg":"trace[1568529870] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-kl2ds-tls; range_end:; response_count:1; response_revision:5575; }","duration":"208.651333ms","start":"2026-09-01T04:32:31.57003Z","end":"2026-09-01T04:32:31.778681Z","steps":["trace[1568529870] 'agreement among raft nodes before linearized reading' (duration: 208.404627ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:31.778722Z","caller":"traceutil/trace.go:171","msg":"trace[2022705443] transaction","detail":"{read_only:false; response_revision:5575; number_of_response:1; }","duration":"208.330004ms","start":"2026-09-01T04:32:31.570353Z","end":"2026-09-01T04:32:31.778683Z","steps":["trace[2022705443] 'process raft request' (duration: 207.579474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:31.778938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.980235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:32:31.779005Z","caller":"traceutil/trace.go:171","msg":"trace[3274994] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:5575; }","duration":"166.103588ms","start":"2026-09-01T04:32:31.612884Z","end":"2026-09-01T04:32:31.778987Z","steps":["trace[3274994] 'agreement among raft nodes before linearized reading' (duration: 165.995025ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:47.828911Z","caller":"traceutil/trace.go:171","msg":"trace[92797102] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"320.465409ms","start":"2026-09-01T04:32:47.508321Z","end":"2026-09-01T04:32:47.828787Z","steps":["trace[92797102] 'process raft request' (duration: 287.868452ms)","trace[92797102] 'compare' (duration: 32.428273ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:32:47.82925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.508288Z","time spent":"320.755247ms","remote":"127.0.0.1:59252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:32:47.91567Z","caller":"traceutil/trace.go:171","msg":"trace[1060781720] transaction","detail":"{read_only:false; response_revision:5847; number_of_response:1; }","duration":"406.986138ms","start":"2026-09-01T04:32:47.508645Z","end":"2026-09-01T04:32:47.915631Z","steps":["trace[1060781720] 'process raft request' (duration: 406.681421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:47.915806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.797478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:32:47.915855Z","caller":"traceutil/trace.go:171","msg":"trace[1831382359] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:5849; }","duration":"390.941452ms","start":"2026-09-01T04:32:47.524895Z","end":"2026-09-01T04:32:47.915837Z","steps":["trace[1831382359] 'agreement among raft nodes before linearized reading' (duration: 390.840119ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:47.915875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.508602Z","time spent":"407.177703ms","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:32:47.915887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.524858Z","time spent":"391.020614ms","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"info","ts":"2026-09-01T04:32:47.915897Z","caller":"traceutil/trace.go:171","msg":"trace[1476182474] transaction","detail":"{read_only:false; response_revision:5848; number_of_response:1; }","duration":"407.061911ms","start":"2026-09-01T04:32:47.508814Z","end":"2026-09-01T04:32:47.915876Z","steps":["trace[1476182474] 'process raft request' (duration: 406.698571ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:47.915864Z","caller":"traceutil/trace.go:171","msg":"trace[672033568] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"406.411003ms","start":"2026-09-01T04:32:47.509385Z","end":"2026-09-01T04:32:47.915796Z","steps":["trace[672033568] 'process raft request' (duration: 406.210787ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:47.915686Z","caller":"traceutil/trace.go:171","msg":"trace[1947002880] linearizableReadLoop","detail":"{readStateIndex:6074; appliedIndex:6070; }","duration":"390.675975ms","start":"2026-09-01T04:32:47.524988Z","end":"2026-09-01T04:32:47.915664Z","steps":["trace[1947002880] 'read index received' (duration: 271.270331ms)","trace[1947002880] 'applied index is now lower than readState.Index' (duration: 119.404114ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:32:47.916097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.509369Z","time spent":"406.613639ms","remote":"127.0.0.1:59252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:32:47.915983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.508802Z","time spent":"407.137013ms","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:32:47.916339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.28227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:32:47.916429Z","caller":"traceutil/trace.go:171","msg":"trace[1197024570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5849; }","duration":"327.447645ms","start":"2026-09-01T04:32:47.588957Z","end":"2026-09-01T04:32:47.916405Z","steps":["trace[1197024570] 'agreement among raft nodes before linearized reading' (duration: 327.27104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:47.916525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.588921Z","time spent":"327.590739ms","remote":"127.0.0.1:58952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T04:32:47.916625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.143365ms","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-09-01T04:32:47.91669Z","caller":"traceutil/trace.go:171","msg":"trace[1899101518] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5849; }","duration":"313.233717ms","start":"2026-09-01T04:32:47.603431Z","end":"2026-09-01T04:32:47.916665Z","steps":["trace[1899101518] 'agreement among raft nodes before linearized reading' (duration: 312.941509ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:47.91673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.603415Z","time spent":"313.305709ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-01T04:32:47.916871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.291688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-kl2ds\" ","response":"range_response_count:1 size:9682"} {"level":"info","ts":"2026-09-01T04:32:47.916959Z","caller":"traceutil/trace.go:171","msg":"trace[414898766] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-kl2ds; range_end:; response_count:1; response_revision:5849; }","duration":"322.430941ms","start":"2026-09-01T04:32:47.594503Z","end":"2026-09-01T04:32:47.916934Z","steps":["trace[414898766] 'agreement among raft nodes before linearized reading' (duration: 322.195575ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:47.917015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:32:47.594479Z","time spent":"322.527194ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9705,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-kl2ds\" "} {"level":"info","ts":"2026-09-01T04:32:57.684401Z","caller":"traceutil/trace.go:171","msg":"trace[469079090] linearizableReadLoop","detail":"{readStateIndex:6282; appliedIndex:6281; }","duration":"155.551705ms","start":"2026-09-01T04:32:57.528821Z","end":"2026-09-01T04:32:57.684373Z","steps":["trace[469079090] 'read index received' (duration: 155.271937ms)","trace[469079090] 'applied index is now lower than readState.Index' (duration: 278.648µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:32:57.684522Z","caller":"traceutil/trace.go:171","msg":"trace[1847189683] transaction","detail":"{read_only:false; response_revision:6052; number_of_response:1; }","duration":"156.149671ms","start":"2026-09-01T04:32:57.528326Z","end":"2026-09-01T04:32:57.684476Z","steps":["trace[1847189683] 'process raft request' (duration: 155.872133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:32:57.684705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.864353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4791"} {"level":"info","ts":"2026-09-01T04:32:57.684759Z","caller":"traceutil/trace.go:171","msg":"trace[410885740] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:6052; }","duration":"155.982567ms","start":"2026-09-01T04:32:57.528758Z","end":"2026-09-01T04:32:57.68474Z","steps":["trace[410885740] 'agreement among raft nodes before linearized reading' (duration: 155.787041ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:57.89873Z","caller":"traceutil/trace.go:171","msg":"trace[1695052744] transaction","detail":"{read_only:false; response_revision:6053; number_of_response:1; }","duration":"197.592152ms","start":"2026-09-01T04:32:57.70111Z","end":"2026-09-01T04:32:57.898702Z","steps":["trace[1695052744] 'process raft request' (duration: 197.379418ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:32:58.078003Z","caller":"traceutil/trace.go:171","msg":"trace[1763771359] transaction","detail":"{read_only:false; response_revision:6054; number_of_response:1; }","duration":"173.445931ms","start":"2026-09-01T04:32:57.904527Z","end":"2026-09-01T04:32:58.077973Z","steps":["trace[1763771359] 'process raft request' (duration: 150.42333ms)","trace[1763771359] 'compare' (duration: 22.676433ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:32:58.106013Z","caller":"traceutil/trace.go:171","msg":"trace[2073659671] transaction","detail":"{read_only:false; response_revision:6055; number_of_response:1; }","duration":"200.590103ms","start":"2026-09-01T04:32:57.905385Z","end":"2026-09-01T04:32:58.105975Z","steps":["trace[2073659671] 'process raft request' (duration: 200.313246ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:33:10.379086Z","caller":"traceutil/trace.go:171","msg":"trace[1305863736] linearizableReadLoop","detail":"{readStateIndex:6414; appliedIndex:6413; }","duration":"127.999684ms","start":"2026-09-01T04:33:10.251049Z","end":"2026-09-01T04:33:10.379048Z","steps":["trace[1305863736] 'read index received' (duration: 16.349844ms)","trace[1305863736] 'applied index is now lower than readState.Index' (duration: 111.64832ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:33:10.379214Z","caller":"traceutil/trace.go:171","msg":"trace[1989731960] transaction","detail":"{read_only:false; response_revision:6181; number_of_response:1; }","duration":"152.581856ms","start":"2026-09-01T04:33:10.226552Z","end":"2026-09-01T04:33:10.379134Z","steps":["trace[1989731960] 'process raft request' (duration: 93.845064ms)","trace[1989731960] 'compare' (duration: 58.467906ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:33:10.37936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.292371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:33:10.379409Z","caller":"traceutil/trace.go:171","msg":"trace[662284226] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:6181; }","duration":"128.444155ms","start":"2026-09-01T04:33:10.250948Z","end":"2026-09-01T04:33:10.379392Z","steps":["trace[662284226] 'agreement among raft nodes before linearized reading' (duration: 128.327042ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:37:10.603102Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4831} {"level":"info","ts":"2026-09-01T04:37:10.660862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4831,"took":"55.994022ms","hash":109913650,"current-db-size-bytes":45608960,"current-db-size":"46 MB","current-db-size-in-use-bytes":38723584,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-09-01T04:37:10.660945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":109913650,"revision":4831,"compact-revision":2965} {"level":"info","ts":"2026-09-01T04:39:24.516767Z","caller":"traceutil/trace.go:171","msg":"trace[2052450174] transaction","detail":"{read_only:false; response_revision:8963; number_of_response:1; }","duration":"120.555969ms","start":"2026-09-01T04:39:24.396171Z","end":"2026-09-01T04:39:24.516727Z","steps":["trace[2052450174] 'process raft request' (duration: 120.350403ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:39:25.633855Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002450.wal"} {"level":"info","ts":"2026-09-01T04:39:26.167508Z","caller":"traceutil/trace.go:171","msg":"trace[1939199625] linearizableReadLoop","detail":"{readStateIndex:9296; appliedIndex:9295; }","duration":"290.396209ms","start":"2026-09-01T04:39:25.877086Z","end":"2026-09-01T04:39:26.167483Z","steps":["trace[1939199625] 'read index received' (duration: 290.255686ms)","trace[1939199625] 'applied index is now lower than readState.Index' (duration: 139.363µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:39:26.167617Z","caller":"traceutil/trace.go:171","msg":"trace[44606677] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"344.752586ms","start":"2026-09-01T04:39:25.822828Z","end":"2026-09-01T04:39:26.167581Z","steps":["trace[44606677] 'process raft request' (duration: 344.405397ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:39:26.16769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.591604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:39:26.167758Z","caller":"traceutil/trace.go:171","msg":"trace[420738910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8971; }","duration":"290.722217ms","start":"2026-09-01T04:39:25.877019Z","end":"2026-09-01T04:39:26.167742Z","steps":["trace[420738910] 'agreement among raft nodes before linearized reading' (duration: 290.560023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:39:26.167804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:39:25.822811Z","time spent":"344.885649ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:39:26.257587Z","caller":"traceutil/trace.go:171","msg":"trace[1048742207] transaction","detail":"{read_only:false; response_revision:8972; number_of_response:1; }","duration":"293.479501ms","start":"2026-09-01T04:39:25.964068Z","end":"2026-09-01T04:39:26.257548Z","steps":["trace[1048742207] 'process raft request' (duration: 290.697077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:39:37.267222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.342945ms","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-09-01T04:39:37.267346Z","caller":"traceutil/trace.go:171","msg":"trace[1988840683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9030; }","duration":"208.53372ms","start":"2026-09-01T04:39:37.058793Z","end":"2026-09-01T04:39:37.267326Z","steps":["trace[1988840683] 'range keys from in-memory index tree' (duration: 208.125509ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:39:37.277265Z","caller":"traceutil/trace.go:171","msg":"trace[438846357] transaction","detail":"{read_only:false; response_revision:9031; number_of_response:1; }","duration":"203.409415ms","start":"2026-09-01T04:39:37.073824Z","end":"2026-09-01T04:39:37.277233Z","steps":["trace[438846357] 'process raft request' (duration: 203.183199ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:39:38.419676Z","caller":"traceutil/trace.go:171","msg":"trace[1500425183] transaction","detail":"{read_only:false; response_revision:9034; number_of_response:1; }","duration":"112.33836ms","start":"2026-09-01T04:39:38.307272Z","end":"2026-09-01T04:39:38.41961Z","steps":["trace[1500425183] 'process raft request' (duration: 78.289674ms)","trace[1500425183] 'compare' (duration: 33.687876ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:39:38.717056Z","caller":"traceutil/trace.go:171","msg":"trace[1435206083] transaction","detail":"{read_only:false; response_revision:9037; number_of_response:1; }","duration":"103.873044ms","start":"2026-09-01T04:39:38.613148Z","end":"2026-09-01T04:39:38.717021Z","steps":["trace[1435206083] 'process raft request' (duration: 50.985216ms)","trace[1435206083] 'compare' (duration: 51.686967ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:08.667382Z","caller":"traceutil/trace.go:171","msg":"trace[222458310] transaction","detail":"{read_only:false; response_revision:9250; number_of_response:1; }","duration":"137.327215ms","start":"2026-09-01T04:40:08.530012Z","end":"2026-09-01T04:40:08.667339Z","steps":["trace[222458310] 'process raft request' (duration: 60.46934ms)","trace[222458310] 'compare' (duration: 76.494615ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:18.675645Z","caller":"traceutil/trace.go:171","msg":"trace[1175934517] transaction","detail":"{read_only:false; response_revision:9302; number_of_response:1; }","duration":"124.685758ms","start":"2026-09-01T04:40:18.550919Z","end":"2026-09-01T04:40:18.675605Z","steps":["trace[1175934517] 'process raft request' (duration: 124.228775ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:18.886501Z","caller":"traceutil/trace.go:171","msg":"trace[769433049] transaction","detail":"{read_only:false; response_revision:9303; number_of_response:1; }","duration":"183.410812ms","start":"2026-09-01T04:40:18.703062Z","end":"2026-09-01T04:40:18.886473Z","steps":["trace[769433049] 'process raft request' (duration: 183.265718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:40:19.423271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.322663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:40:19.423499Z","caller":"traceutil/trace.go:171","msg":"trace[788269530] transaction","detail":"{read_only:false; response_revision:9308; number_of_response:1; }","duration":"228.670466ms","start":"2026-09-01T04:40:19.194796Z","end":"2026-09-01T04:40:19.423466Z","steps":["trace[788269530] 'process raft request' (duration: 123.028034ms)","trace[788269530] 'compare' (duration: 105.170919ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:19.423584Z","caller":"traceutil/trace.go:171","msg":"trace[451539604] transaction","detail":"{read_only:false; response_revision:9309; number_of_response:1; }","duration":"137.755707ms","start":"2026-09-01T04:40:19.285817Z","end":"2026-09-01T04:40:19.423573Z","steps":["trace[451539604] 'process raft request' (duration: 137.598642ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:19.423508Z","caller":"traceutil/trace.go:171","msg":"trace[106897074] linearizableReadLoop","detail":"{readStateIndex:9646; appliedIndex:9645; }","duration":"162.203387ms","start":"2026-09-01T04:40:19.261284Z","end":"2026-09-01T04:40:19.423487Z","steps":["trace[106897074] 'read index received' (duration: 56.522405ms)","trace[106897074] 'applied index is now lower than readState.Index' (duration: 105.679462ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:40:19.449173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.86602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pntqk\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-01T04:40:19.449508Z","caller":"traceutil/trace.go:171","msg":"trace[365613800] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pntqk; range_end:; response_count:1; response_revision:9309; }","duration":"188.24995ms","start":"2026-09-01T04:40:19.261222Z","end":"2026-09-01T04:40:19.449472Z","steps":["trace[365613800] 'agreement among raft nodes before linearized reading' (duration: 162.32493ms)","trace[365613800] 'range keys from in-memory index tree' (duration: 25.477538ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:40:22.611158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.869275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:40:22.611488Z","caller":"traceutil/trace.go:171","msg":"trace[555213015] linearizableReadLoop","detail":"{readStateIndex:9671; appliedIndex:9668; }","duration":"101.730927ms","start":"2026-09-01T04:40:22.509734Z","end":"2026-09-01T04:40:22.611464Z","steps":["trace[555213015] 'read index received' (duration: 51.041µs)","trace[555213015] 'applied index is now lower than readState.Index' (duration: 101.678716ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:22.611483Z","caller":"traceutil/trace.go:171","msg":"trace[7482417] transaction","detail":"{read_only:false; response_revision:9330; number_of_response:1; }","duration":"298.592856ms","start":"2026-09-01T04:40:22.312857Z","end":"2026-09-01T04:40:22.61145Z","steps":["trace[7482417] 'process raft request' (duration: 64.333332ms)","trace[7482417] 'compare' (duration: 233.759922ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:22.611549Z","caller":"traceutil/trace.go:171","msg":"trace[1884196378] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9331; }","duration":"297.032636ms","start":"2026-09-01T04:40:22.314509Z","end":"2026-09-01T04:40:22.611542Z","steps":["trace[1884196378] 'process raft request' (duration: 296.759569ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:22.611944Z","caller":"traceutil/trace.go:171","msg":"trace[382192875] transaction","detail":"{read_only:false; response_revision:9332; number_of_response:1; }","duration":"293.606214ms","start":"2026-09-01T04:40:22.318317Z","end":"2026-09-01T04:40:22.611923Z","steps":["trace[382192875] 'process raft request' (duration: 293.08021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:40:22.612012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.265212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5h9mr\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-09-01T04:40:22.612051Z","caller":"traceutil/trace.go:171","msg":"trace[1249042046] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5h9mr; range_end:; response_count:1; response_revision:9332; }","duration":"102.371604ms","start":"2026-09-01T04:40:22.50967Z","end":"2026-09-01T04:40:22.612042Z","steps":["trace[1249042046] 'agreement among raft nodes before linearized reading' (duration: 102.283272ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:22.627205Z","caller":"traceutil/trace.go:171","msg":"trace[650914541] transaction","detail":"{read_only:false; response_revision:9333; number_of_response:1; }","duration":"117.054995ms","start":"2026-09-01T04:40:22.510115Z","end":"2026-09-01T04:40:22.62717Z","steps":["trace[650914541] 'process raft request' (duration: 116.791648ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:22.746616Z","caller":"traceutil/trace.go:171","msg":"trace[884946896] transaction","detail":"{read_only:false; response_revision:9334; number_of_response:1; }","duration":"123.132637ms","start":"2026-09-01T04:40:22.623436Z","end":"2026-09-01T04:40:22.746568Z","steps":["trace[884946896] 'process raft request' (duration: 101.4571ms)","trace[884946896] 'compare' (duration: 21.494702ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:40:22.746723Z","caller":"traceutil/trace.go:171","msg":"trace[1190733861] linearizableReadLoop","detail":"{readStateIndex:9673; appliedIndex:9672; }","duration":"116.85102ms","start":"2026-09-01T04:40:22.629833Z","end":"2026-09-01T04:40:22.746684Z","steps":["trace[1190733861] 'read index received' (duration: 95.06468ms)","trace[1190733861] 'applied index is now lower than readState.Index' (duration: 21.78259ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:40:22.746879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.017954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-5h9mr\" ","response":"range_response_count:1 size:4652"} {"level":"info","ts":"2026-09-01T04:40:22.74694Z","caller":"traceutil/trace.go:171","msg":"trace[322984880] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-5h9mr; range_end:; response_count:1; response_revision:9335; }","duration":"117.121557ms","start":"2026-09-01T04:40:22.629801Z","end":"2026-09-01T04:40:22.746923Z","steps":["trace[322984880] 'agreement among raft nodes before linearized reading' (duration: 116.998414ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:40:51.973448Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T04:40:51.979607Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T04:40:51.979784Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T04:42:10.610494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7857} {"level":"info","ts":"2026-09-01T04:42:10.715624Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7857,"took":"102.516198ms","hash":1312336033,"current-db-size-bytes":45608960,"current-db-size":"46 MB","current-db-size-in-use-bytes":25432064,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-01T04:42:10.715787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1312336033,"revision":7857,"compact-revision":4831} {"level":"info","ts":"2026-09-01T04:47:10.618564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10342} {"level":"info","ts":"2026-09-01T04:47:10.702461Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10342,"took":"82.012083ms","hash":621898477,"current-db-size-bytes":45608960,"current-db-size":"46 MB","current-db-size-in-use-bytes":24616960,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-01T04:47:10.702543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":621898477,"revision":10342,"compact-revision":7857} {"level":"info","ts":"2026-09-01T04:47:36.319318Z","caller":"traceutil/trace.go:171","msg":"trace[1276532869] transaction","detail":"{read_only:false; response_revision:12632; number_of_response:1; }","duration":"115.025242ms","start":"2026-09-01T04:47:36.20426Z","end":"2026-09-01T04:47:36.319285Z","steps":["trace[1276532869] 'process raft request' (duration: 114.837167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:47:55.649373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.711961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-01T04:47:55.649508Z","caller":"traceutil/trace.go:171","msg":"trace[980858287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12741; }","duration":"115.913656ms","start":"2026-09-01T04:47:55.533573Z","end":"2026-09-01T04:47:55.649486Z","steps":["trace[980858287] 'range keys from in-memory index tree' (duration: 115.455314ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:47:55.649556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.208577ms","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-09-01T04:47:55.649643Z","caller":"traceutil/trace.go:171","msg":"trace[715111715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12741; }","duration":"124.361101ms","start":"2026-09-01T04:47:55.525259Z","end":"2026-09-01T04:47:55.649621Z","steps":["trace[715111715] 'range keys from in-memory index tree' (duration: 124.003232ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:52:10.630967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12295} {"level":"info","ts":"2026-09-01T04:52:10.756529Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12295,"took":"122.888505ms","hash":1241630351,"current-db-size-bytes":45608960,"current-db-size":"46 MB","current-db-size-in-use-bytes":26697728,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-01T04:52:10.756604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1241630351,"revision":12295,"compact-revision":10342} {"level":"info","ts":"2026-09-01T04:53:36.2865Z","caller":"traceutil/trace.go:171","msg":"trace[1498728441] transaction","detail":"{read_only:false; response_revision:15038; number_of_response:1; }","duration":"121.587799ms","start":"2026-09-01T04:53:36.16488Z","end":"2026-09-01T04:53:36.286468Z","steps":["trace[1498728441] 'process raft request' (duration: 82.2321ms)","trace[1498728441] 'compare' (duration: 39.258866ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:53:41.465035Z","caller":"traceutil/trace.go:171","msg":"trace[876505906] transaction","detail":"{read_only:false; response_revision:15061; number_of_response:1; }","duration":"130.335173ms","start":"2026-09-01T04:53:41.334668Z","end":"2026-09-01T04:53:41.465003Z","steps":["trace[876505906] 'process raft request' (duration: 130.143498ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:18.84427Z","caller":"traceutil/trace.go:171","msg":"trace[1947573099] transaction","detail":"{read_only:false; response_revision:15623; number_of_response:1; }","duration":"258.421468ms","start":"2026-09-01T04:55:18.585824Z","end":"2026-09-01T04:55:18.844245Z","steps":["trace[1947573099] 'process raft request' (duration: 258.281604ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:18.98279Z","caller":"traceutil/trace.go:171","msg":"trace[1287722657] transaction","detail":"{read_only:false; response_revision:15624; number_of_response:1; }","duration":"369.700042ms","start":"2026-09-01T04:55:18.613063Z","end":"2026-09-01T04:55:18.982763Z","steps":["trace[1287722657] 'process raft request' (duration: 323.644315ms)","trace[1287722657] 'compare' (duration: 45.647876ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:55:18.982981Z","caller":"traceutil/trace.go:171","msg":"trace[906289905] transaction","detail":"{read_only:false; response_revision:15625; number_of_response:1; }","duration":"280.562466ms","start":"2026-09-01T04:55:18.702398Z","end":"2026-09-01T04:55:18.982961Z","steps":["trace[906289905] 'process raft request' (duration: 280.112534ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:18.982995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:18.613035Z","time spent":"369.879947ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:55:18.982624Z","caller":"traceutil/trace.go:171","msg":"trace[1864148877] linearizableReadLoop","detail":"{readStateIndex:16179; appliedIndex:16178; }","duration":"340.071092ms","start":"2026-09-01T04:55:18.642524Z","end":"2026-09-01T04:55:18.982595Z","steps":["trace[1864148877] 'read index received' (duration: 202.149448ms)","trace[1864148877] 'applied index is now lower than readState.Index' (duration: 137.920554ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:55:18.983284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.248976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pntqk\" ","response":"range_response_count:1 size:6447"} {"level":"warn","ts":"2026-09-01T04:55:18.983296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.448589ms","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-09-01T04:55:18.983315Z","caller":"traceutil/trace.go:171","msg":"trace[1345338643] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pntqk; range_end:; response_count:1; response_revision:15625; }","duration":"185.323909ms","start":"2026-09-01T04:55:18.797982Z","end":"2026-09-01T04:55:18.983306Z","steps":["trace[1345338643] 'agreement among raft nodes before linearized reading' (duration: 185.200375ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:18.983333Z","caller":"traceutil/trace.go:171","msg":"trace[380450950] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15625; }","duration":"105.690086ms","start":"2026-09-01T04:55:18.87763Z","end":"2026-09-01T04:55:18.98332Z","steps":["trace[380450950] 'agreement among raft nodes before linearized reading' (duration: 105.591803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:18.983296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.129411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:55:18.983427Z","caller":"traceutil/trace.go:171","msg":"trace[664303698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15625; }","duration":"108.299815ms","start":"2026-09-01T04:55:18.875114Z","end":"2026-09-01T04:55:18.983414Z","steps":["trace[664303698] 'agreement among raft nodes before linearized reading' (duration: 108.133781ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:18.983256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.72062ms","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-09-01T04:55:18.983637Z","caller":"traceutil/trace.go:171","msg":"trace[29753107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15625; }","duration":"340.984097ms","start":"2026-09-01T04:55:18.642517Z","end":"2026-09-01T04:55:18.983501Z","steps":["trace[29753107] 'agreement among raft nodes before linearized reading' (duration: 340.331109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:18.983842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:18.642474Z","time spent":"341.352407ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-01T04:55:43.381285Z","caller":"traceutil/trace.go:171","msg":"trace[1038991147] transaction","detail":"{read_only:false; response_revision:15941; number_of_response:1; }","duration":"435.179209ms","start":"2026-09-01T04:55:42.946081Z","end":"2026-09-01T04:55:43.38126Z","steps":["trace[1038991147] 'process raft request' (duration: 435.033345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.381425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:42.946055Z","time spent":"435.302072ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:55:43.461036Z","caller":"traceutil/trace.go:171","msg":"trace[601920187] transaction","detail":"{read_only:false; response_revision:15942; number_of_response:1; }","duration":"393.681004ms","start":"2026-09-01T04:55:43.067328Z","end":"2026-09-01T04:55:43.461009Z","steps":["trace[601920187] 'process raft request' (duration: 391.782613ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.46121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:43.067303Z","time spent":"393.823587ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:55:43.461387Z","caller":"traceutil/trace.go:171","msg":"trace[1991401577] transaction","detail":"{read_only:false; response_revision:15943; number_of_response:1; }","duration":"378.405726ms","start":"2026-09-01T04:55:43.082972Z","end":"2026-09-01T04:55:43.461377Z","steps":["trace[1991401577] 'process raft request' (duration: 378.000095ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:43.461459Z","caller":"traceutil/trace.go:171","msg":"trace[1280263778] transaction","detail":"{read_only:false; response_revision:15944; number_of_response:1; }","duration":"144.532962ms","start":"2026-09-01T04:55:43.316912Z","end":"2026-09-01T04:55:43.461445Z","steps":["trace[1280263778] 'process raft request' (duration: 144.302266ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:43.461485Z","caller":"traceutil/trace.go:171","msg":"trace[1805374733] linearizableReadLoop","detail":"{readStateIndex:16512; appliedIndex:16509; }","duration":"345.642752ms","start":"2026-09-01T04:55:43.115814Z","end":"2026-09-01T04:55:43.461457Z","steps":["trace[1805374733] 'read index received' (duration: 265.24869ms)","trace[1805374733] 'applied index is now lower than readState.Index' (duration: 80.392692ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:55:43.461603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:43.082946Z","time spent":"378.468217ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:55:43.461649Z","caller":"traceutil/trace.go:171","msg":"trace[1086038579] transaction","detail":"{read_only:false; response_revision:15945; number_of_response:1; }","duration":"105.79225ms","start":"2026-09-01T04:55:43.355844Z","end":"2026-09-01T04:55:43.461636Z","steps":["trace[1086038579] 'process raft request' (duration: 105.493973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.462731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.902566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-01T04:55:43.462817Z","caller":"traceutil/trace.go:171","msg":"trace[1204672198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15946; }","duration":"347.035429ms","start":"2026-09-01T04:55:43.115762Z","end":"2026-09-01T04:55:43.462797Z","steps":["trace[1204672198] 'agreement among raft nodes before linearized reading' (duration: 346.077293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.46287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:43.115737Z","time spent":"347.120842ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T04:55:43.462996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.375319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-5jmr9\" ","response":"range_response_count:1 size:20561"} {"level":"info","ts":"2026-09-01T04:55:43.46307Z","caller":"traceutil/trace.go:171","msg":"trace[2036267361] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-5jmr9; range_end:; response_count:1; response_revision:15946; }","duration":"141.484141ms","start":"2026-09-01T04:55:43.321568Z","end":"2026-09-01T04:55:43.463052Z","steps":["trace[2036267361] 'agreement among raft nodes before linearized reading' (duration: 140.33506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.463391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.358237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pntqk\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-09-01T04:55:43.46345Z","caller":"traceutil/trace.go:171","msg":"trace[2069556395] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pntqk; range_end:; response_count:1; response_revision:15946; }","duration":"255.449429ms","start":"2026-09-01T04:55:43.207985Z","end":"2026-09-01T04:55:43.463434Z","steps":["trace[2069556395] 'agreement among raft nodes before linearized reading' (duration: 253.944829ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.46367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.789397ms","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-09-01T04:55:43.463777Z","caller":"traceutil/trace.go:171","msg":"trace[2043063722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15946; }","duration":"327.937831ms","start":"2026-09-01T04:55:43.135811Z","end":"2026-09-01T04:55:43.463748Z","steps":["trace[2043063722] 'agreement among raft nodes before linearized reading' (duration: 326.091301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.463838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:43.135791Z","time spent":"328.031723ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-01T04:55:43.590976Z","caller":"traceutil/trace.go:171","msg":"trace[1087853699] linearizableReadLoop","detail":"{readStateIndex:16517; appliedIndex:16515; }","duration":"122.727201ms","start":"2026-09-01T04:55:43.468228Z","end":"2026-09-01T04:55:43.590955Z","steps":["trace[1087853699] 'read index received' (duration: 109.738715ms)","trace[1087853699] 'applied index is now lower than readState.Index' (duration: 12.987486ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:55:43.591072Z","caller":"traceutil/trace.go:171","msg":"trace[1682513123] transaction","detail":"{read_only:false; response_revision:15947; number_of_response:1; }","duration":"124.143288ms","start":"2026-09-01T04:55:43.466899Z","end":"2026-09-01T04:55:43.591043Z","steps":["trace[1682513123] 'process raft request' (duration: 111.0795ms)","trace[1682513123] 'compare' (duration: 12.810901ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:55:43.591223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.94339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19870"} {"level":"info","ts":"2026-09-01T04:55:43.59131Z","caller":"traceutil/trace.go:171","msg":"trace[1786144652] transaction","detail":"{read_only:false; response_revision:15948; number_of_response:1; }","duration":"123.818691ms","start":"2026-09-01T04:55:43.467467Z","end":"2026-09-01T04:55:43.591286Z","steps":["trace[1786144652] 'process raft request' (duration: 123.449461ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:43.591291Z","caller":"traceutil/trace.go:171","msg":"trace[906313602] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:15948; }","duration":"122.043042ms","start":"2026-09-01T04:55:43.46923Z","end":"2026-09-01T04:55:43.591273Z","steps":["trace[906313602] 'agreement among raft nodes before linearized reading' (duration: 121.869988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.591786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.563274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:626090"} {"level":"info","ts":"2026-09-01T04:55:43.591866Z","caller":"traceutil/trace.go:171","msg":"trace[1858832519] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:15948; }","duration":"123.653116ms","start":"2026-09-01T04:55:43.468196Z","end":"2026-09-01T04:55:43.591849Z","steps":["trace[1858832519] 'agreement among raft nodes before linearized reading' (duration: 122.895846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:43.592778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.444081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:626090"} {"level":"info","ts":"2026-09-01T04:55:43.59297Z","caller":"traceutil/trace.go:171","msg":"trace[1570342813] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:15948; }","duration":"123.649696ms","start":"2026-09-01T04:55:43.4693Z","end":"2026-09-01T04:55:43.59295Z","steps":["trace[1570342813] 'agreement among raft nodes before linearized reading' (duration: 121.95667ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:56:27.545588Z","caller":"traceutil/trace.go:171","msg":"trace[551267153] transaction","detail":"{read_only:false; response_revision:16511; number_of_response:1; }","duration":"112.767476ms","start":"2026-09-01T04:56:27.432762Z","end":"2026-09-01T04:56:27.545529Z","steps":["trace[551267153] 'process raft request' (duration: 112.387516ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:56:36.037768Z","caller":"traceutil/trace.go:171","msg":"trace[951394975] linearizableReadLoop","detail":"{readStateIndex:17163; appliedIndex:17162; }","duration":"102.930273ms","start":"2026-09-01T04:56:35.934798Z","end":"2026-09-01T04:56:36.037728Z","steps":["trace[951394975] 'read index received' (duration: 102.693697ms)","trace[951394975] 'applied index is now lower than readState.Index' (duration: 234.486µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:56:36.037839Z","caller":"traceutil/trace.go:171","msg":"trace[1076483619] transaction","detail":"{read_only:false; response_revision:16577; number_of_response:1; }","duration":"141.426009ms","start":"2026-09-01T04:56:35.896374Z","end":"2026-09-01T04:56:36.0378Z","steps":["trace[1076483619] 'process raft request' (duration: 141.071639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:56:36.038102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.277662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-01T04:56:36.038153Z","caller":"traceutil/trace.go:171","msg":"trace[333476437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16577; }","duration":"103.403656ms","start":"2026-09-01T04:56:35.934733Z","end":"2026-09-01T04:56:36.038137Z","steps":["trace[333476437] 'agreement among raft nodes before linearized reading' (duration: 103.156149ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:56:59.783865Z","caller":"traceutil/trace.go:171","msg":"trace[1982434264] transaction","detail":"{read_only:false; response_revision:16704; number_of_response:1; }","duration":"103.769566ms","start":"2026-09-01T04:56:59.680056Z","end":"2026-09-01T04:56:59.783825Z","steps":["trace[1982434264] 'process raft request' (duration: 103.491198ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:57:00.04473Z","caller":"traceutil/trace.go:171","msg":"trace[24221779] transaction","detail":"{read_only:false; response_revision:16706; number_of_response:1; }","duration":"213.963003ms","start":"2026-09-01T04:56:59.83074Z","end":"2026-09-01T04:57:00.044703Z","steps":["trace[24221779] 'process raft request' (duration: 213.793069ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:57:00.108318Z","caller":"traceutil/trace.go:171","msg":"trace[1095395914] linearizableReadLoop","detail":"{readStateIndex:17298; appliedIndex:17296; }","duration":"233.806171ms","start":"2026-09-01T04:56:59.874466Z","end":"2026-09-01T04:57:00.108273Z","steps":["trace[1095395914] 'read index received' (duration: 170.055792ms)","trace[1095395914] 'applied index is now lower than readState.Index' (duration: 63.748969ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:57:00.108511Z","caller":"traceutil/trace.go:171","msg":"trace[734323844] transaction","detail":"{read_only:false; response_revision:16707; number_of_response:1; }","duration":"272.500742ms","start":"2026-09-01T04:56:59.835977Z","end":"2026-09-01T04:57:00.108477Z","steps":["trace[734323844] 'process raft request' (duration: 264.687014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:57:00.108726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.220782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:57:00.108808Z","caller":"traceutil/trace.go:171","msg":"trace[1104499298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16707; }","duration":"234.373536ms","start":"2026-09-01T04:56:59.874413Z","end":"2026-09-01T04:57:00.108787Z","steps":["trace[1104499298] 'agreement among raft nodes before linearized reading' (duration: 234.217032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:57:00.109011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.851762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5451"} {"level":"info","ts":"2026-09-01T04:57:00.109064Z","caller":"traceutil/trace.go:171","msg":"trace[2116374997] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16707; }","duration":"105.957763ms","start":"2026-09-01T04:57:00.003092Z","end":"2026-09-01T04:57:00.10905Z","steps":["trace[2116374997] 'agreement among raft nodes before linearized reading' (duration: 105.789939ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:57:00.372951Z","caller":"traceutil/trace.go:171","msg":"trace[1794958178] linearizableReadLoop","detail":"{readStateIndex:17299; appliedIndex:17298; }","duration":"199.927228ms","start":"2026-09-01T04:57:00.172996Z","end":"2026-09-01T04:57:00.372923Z","steps":["trace[1794958178] 'read index received' (duration: 138.899312ms)","trace[1794958178] 'applied index is now lower than readState.Index' (duration: 61.026956ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:57:00.373094Z","caller":"traceutil/trace.go:171","msg":"trace[553617638] transaction","detail":"{read_only:false; response_revision:16708; number_of_response:1; }","duration":"209.912464ms","start":"2026-09-01T04:57:00.163152Z","end":"2026-09-01T04:57:00.373064Z","steps":["trace[553617638] 'process raft request' (duration: 148.793976ms)","trace[553617638] 'compare' (duration: 60.863521ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:57:00.373153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.168686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-01T04:57:00.373193Z","caller":"traceutil/trace.go:171","msg":"trace[2061579096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16708; }","duration":"200.223447ms","start":"2026-09-01T04:57:00.172957Z","end":"2026-09-01T04:57:00.37318Z","steps":["trace[2061579096] 'agreement among raft nodes before linearized reading' (duration: 200.074263ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:57:10.638556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14401} {"level":"info","ts":"2026-09-01T04:57:10.714784Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14401,"took":"73.690264ms","hash":1159423695,"current-db-size-bytes":45608960,"current-db-size":"46 MB","current-db-size-in-use-bytes":29507584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T04:57:10.714924Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1159423695,"revision":14401,"compact-revision":12295} {"level":"info","ts":"2026-09-01T05:02:10.64558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16761} {"level":"info","ts":"2026-09-01T05:02:10.752363Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16761,"took":"103.161505ms","hash":98895637,"current-db-size-bytes":46452736,"current-db-size":"46 MB","current-db-size-in-use-bytes":31719424,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T05:02:10.752483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":98895637,"revision":16761,"compact-revision":14401} {"level":"info","ts":"2026-09-01T05:02:55.240711Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:02:55.247295Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T05:02:55.247591Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T05:03:27.540965Z","caller":"traceutil/trace.go:171","msg":"trace[1371917527] transaction","detail":"{read_only:false; response_revision:19541; number_of_response:1; }","duration":"102.852558ms","start":"2026-09-01T05:03:27.438085Z","end":"2026-09-01T05:03:27.540938Z","steps":["trace[1371917527] 'process raft request' (duration: 102.711445ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:03:27.628333Z","caller":"traceutil/trace.go:171","msg":"trace[1678896264] transaction","detail":"{read_only:false; response_revision:19542; number_of_response:1; }","duration":"186.43786ms","start":"2026-09-01T05:03:27.441859Z","end":"2026-09-01T05:03:27.628297Z","steps":["trace[1678896264] 'process raft request' (duration: 161.764741ms)","trace[1678896264] 'compare' (duration: 24.527065ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:03:28.583448Z","caller":"traceutil/trace.go:171","msg":"trace[1011937737] linearizableReadLoop","detail":"{readStateIndex:20230; appliedIndex:20229; }","duration":"408.185253ms","start":"2026-09-01T05:03:28.17524Z","end":"2026-09-01T05:03:28.583425Z","steps":["trace[1011937737] 'read index received' (duration: 407.928786ms)","trace[1011937737] 'applied index is now lower than readState.Index' (duration: 255.527µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:03:28.583554Z","caller":"traceutil/trace.go:171","msg":"trace[323851554] transaction","detail":"{read_only:false; response_revision:19545; number_of_response:1; }","duration":"447.683977ms","start":"2026-09-01T05:03:28.135844Z","end":"2026-09-01T05:03:28.583528Z","steps":["trace[323851554] 'process raft request' (duration: 447.43068ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:03:28.583617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.349937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T05:03:28.583654Z","caller":"traceutil/trace.go:171","msg":"trace[356717964] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:19545; }","duration":"408.430589ms","start":"2026-09-01T05:03:28.17521Z","end":"2026-09-01T05:03:28.583641Z","steps":["trace[356717964] 'agreement among raft nodes before linearized reading' (duration: 408.351217ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:03:28.583712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:03:28.135824Z","time spent":"447.799831ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20781,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:03:28.583718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:03:28.175197Z","time spent":"408.511221ms","remote":"127.0.0.1:35090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-09-01T05:03:28.584501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.10174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-2cxmx\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-09-01T05:03:28.584589Z","caller":"traceutil/trace.go:171","msg":"trace[911307041] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-2cxmx; range_end:; response_count:1; response_revision:19545; }","duration":"408.279135ms","start":"2026-09-01T05:03:28.176298Z","end":"2026-09-01T05:03:28.584577Z","steps":["trace[911307041] 'agreement among raft nodes before linearized reading' (duration: 407.517234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:03:28.584619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:03:28.176234Z","time spent":"408.376168ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9736,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-2cxmx\" "} {"level":"warn","ts":"2026-09-01T05:03:28.584641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.467565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T05:03:28.584779Z","caller":"traceutil/trace.go:171","msg":"trace[677294170] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:19545; }","duration":"215.662321ms","start":"2026-09-01T05:03:28.369093Z","end":"2026-09-01T05:03:28.584755Z","steps":["trace[677294170] 'agreement among raft nodes before linearized reading' (duration: 215.379403ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:03:36.880239Z","caller":"traceutil/trace.go:171","msg":"trace[1038514899] transaction","detail":"{read_only:false; response_revision:19687; number_of_response:1; }","duration":"121.3106ms","start":"2026-09-01T05:03:36.758894Z","end":"2026-09-01T05:03:36.880204Z","steps":["trace[1038514899] 'process raft request' (duration: 121.076624ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:03:36.880372Z","caller":"traceutil/trace.go:171","msg":"trace[1941399099] transaction","detail":"{read_only:false; response_revision:19688; number_of_response:1; }","duration":"118.755463ms","start":"2026-09-01T05:03:36.761578Z","end":"2026-09-01T05:03:36.880333Z","steps":["trace[1941399099] 'process raft request' (duration: 118.539977ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:03:36.880524Z","caller":"traceutil/trace.go:171","msg":"trace[980343363] transaction","detail":"{read_only:false; response_revision:19689; number_of_response:1; }","duration":"112.841935ms","start":"2026-09-01T05:03:36.76759Z","end":"2026-09-01T05:03:36.880432Z","steps":["trace[980343363] 'process raft request' (duration: 112.584368ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:07:10.658171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19025} {"level":"info","ts":"2026-09-01T05:07:10.765448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19025,"took":"104.020532ms","hash":2726558629,"current-db-size-bytes":48984064,"current-db-size":"49 MB","current-db-size-in-use-bytes":34529280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T05:07:10.76552Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2726558629,"revision":19025,"compact-revision":16761} {"level":"info","ts":"2026-09-01T05:09:02.80013Z","caller":"traceutil/trace.go:171","msg":"trace[1925356932] linearizableReadLoop","detail":"{readStateIndex:22999; appliedIndex:22998; }","duration":"211.791144ms","start":"2026-09-01T05:09:02.588315Z","end":"2026-09-01T05:09:02.800106Z","steps":["trace[1925356932] 'read index received' (duration: 211.597409ms)","trace[1925356932] 'applied index is now lower than readState.Index' (duration: 192.915µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:09:02.800222Z","caller":"traceutil/trace.go:171","msg":"trace[121281752] transaction","detail":"{read_only:false; response_revision:22228; number_of_response:1; }","duration":"212.628956ms","start":"2026-09-01T05:09:02.58757Z","end":"2026-09-01T05:09:02.800199Z","steps":["trace[121281752] 'process raft request' (duration: 212.396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:02.800318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.975959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T05:09:02.800385Z","caller":"traceutil/trace.go:171","msg":"trace[126825082] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:22228; }","duration":"212.073621ms","start":"2026-09-01T05:09:02.588292Z","end":"2026-09-01T05:09:02.800366Z","steps":["trace[126825082] 'agreement among raft nodes before linearized reading' (duration: 211.959638ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:02.800683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.989066ms","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-09-01T05:09:02.800707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.298094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-01T05:09:02.800777Z","caller":"traceutil/trace.go:171","msg":"trace[126152162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22228; }","duration":"207.095788ms","start":"2026-09-01T05:09:02.59366Z","end":"2026-09-01T05:09:02.800756Z","steps":["trace[126152162] 'agreement among raft nodes before linearized reading' (duration: 206.757649ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:09:02.800869Z","caller":"traceutil/trace.go:171","msg":"trace[1166654211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22228; }","duration":"207.444758ms","start":"2026-09-01T05:09:02.593366Z","end":"2026-09-01T05:09:02.800811Z","steps":["trace[1166654211] 'agreement among raft nodes before linearized reading' (duration: 207.045687ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:09:19.108921Z","caller":"traceutil/trace.go:171","msg":"trace[415253703] transaction","detail":"{read_only:false; response_revision:22310; number_of_response:1; }","duration":"230.506795ms","start":"2026-09-01T05:09:18.87839Z","end":"2026-09-01T05:09:19.108897Z","steps":["trace[415253703] 'process raft request' (duration: 230.372862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:19.44487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.650312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:09:19.445148Z","caller":"traceutil/trace.go:171","msg":"trace[158083977] linearizableReadLoop","detail":"{readStateIndex:23087; appliedIndex:23084; }","duration":"549.102555ms","start":"2026-09-01T05:09:18.896023Z","end":"2026-09-01T05:09:19.445126Z","steps":["trace[158083977] 'read index received' (duration: 212.789691ms)","trace[158083977] 'applied index is now lower than readState.Index' (duration: 336.311534ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:09:19.445438Z","caller":"traceutil/trace.go:171","msg":"trace[603473811] transaction","detail":"{read_only:false; response_revision:22315; number_of_response:1; }","duration":"477.211712ms","start":"2026-09-01T05:09:18.968197Z","end":"2026-09-01T05:09:19.445409Z","steps":["trace[603473811] 'process raft request' (duration: 476.887754ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:09:19.445514Z","caller":"traceutil/trace.go:171","msg":"trace[1550946455] transaction","detail":"{read_only:false; response_revision:22311; number_of_response:1; }","duration":"564.685542ms","start":"2026-09-01T05:09:18.880793Z","end":"2026-09-01T05:09:19.445478Z","steps":["trace[1550946455] 'process raft request' (duration: 325.329081ms)","trace[1550946455] 'compare' (duration: 238.521529ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:09:19.445568Z","caller":"traceutil/trace.go:171","msg":"trace[1813697136] transaction","detail":"{read_only:false; response_revision:22314; number_of_response:1; }","duration":"481.255451ms","start":"2026-09-01T05:09:18.9643Z","end":"2026-09-01T05:09:19.445555Z","steps":["trace[1813697136] 'process raft request' (duration: 480.753448ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:19.445636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.964279Z","time spent":"481.315543ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:09:19.445631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.880766Z","time spent":"564.804176ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:09:19.445623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.568547ms","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-09-01T05:09:19.445638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.968175Z","time spent":"477.379147ms","remote":"127.0.0.1:59236","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-09-01T05:09:19.445269Z","caller":"traceutil/trace.go:171","msg":"trace[1549983283] transaction","detail":"{read_only:false; response_revision:22312; number_of_response:1; }","duration":"562.589055ms","start":"2026-09-01T05:09:18.882623Z","end":"2026-09-01T05:09:19.445212Z","steps":["trace[1549983283] 'process raft request' (duration: 562.344909ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:09:19.44573Z","caller":"traceutil/trace.go:171","msg":"trace[123929520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22315; }","duration":"549.66124ms","start":"2026-09-01T05:09:18.896018Z","end":"2026-09-01T05:09:19.445679Z","steps":["trace[123929520] 'agreement among raft nodes before linearized reading' (duration: 549.426553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:19.445814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.882608Z","time spent":"563.15938ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:09:19.445808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.895982Z","time spent":"549.809903ms","remote":"127.0.0.1:59236","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":"info","ts":"2026-09-01T05:09:19.445251Z","caller":"traceutil/trace.go:171","msg":"trace[1036569113] transaction","detail":"{read_only:false; response_revision:22313; number_of_response:1; }","duration":"505.681424ms","start":"2026-09-01T05:09:18.939558Z","end":"2026-09-01T05:09:19.44524Z","steps":["trace[1036569113] 'process raft request' (duration: 505.459668ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:19.445904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.939541Z","time spent":"506.316871ms","remote":"127.0.0.1:59236","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-09-01T05:09:19.492899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.496947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-01T05:09:19.492973Z","caller":"traceutil/trace.go:171","msg":"trace[504051733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22315; }","duration":"521.62532ms","start":"2026-09-01T05:09:18.971331Z","end":"2026-09-01T05:09:19.492956Z","steps":["trace[504051733] 'agreement among raft nodes before linearized reading' (duration: 521.411345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:19.493013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.971316Z","time spent":"521.688852ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T05:09:19.493174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.236376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-ksc44.18d119f7dc3634bb\" ","response":"range_response_count:1 size:1387"} {"level":"warn","ts":"2026-09-01T05:09:19.493257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.524331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-01T05:09:19.493267Z","caller":"traceutil/trace.go:171","msg":"trace[132030128] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-ksc44.18d119f7dc3634bb; range_end:; response_count:1; response_revision:22315; }","duration":"120.370629ms","start":"2026-09-01T05:09:19.372873Z","end":"2026-09-01T05:09:19.493243Z","steps":["trace[132030128] 'agreement among raft nodes before linearized reading' (duration: 120.109082ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:09:19.493287Z","caller":"traceutil/trace.go:171","msg":"trace[678263955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22315; }","duration":"520.601374ms","start":"2026-09-01T05:09:18.972676Z","end":"2026-09-01T05:09:19.493277Z","steps":["trace[678263955] 'agreement among raft nodes before linearized reading' (duration: 520.514772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:19.493311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:18.972664Z","time spent":"520.641485ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-01T05:09:19.734742Z","caller":"traceutil/trace.go:171","msg":"trace[1749226085] transaction","detail":"{read_only:false; response_revision:22316; number_of_response:1; }","duration":"278.879898ms","start":"2026-09-01T05:09:19.455832Z","end":"2026-09-01T05:09:19.734712Z","steps":["trace[1749226085] 'process raft request' (duration: 278.691713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:20.100642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.369636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:09:20.100877Z","caller":"traceutil/trace.go:171","msg":"trace[338385586] linearizableReadLoop","detail":"{readStateIndex:23095; appliedIndex:23092; }","duration":"578.101901ms","start":"2026-09-01T05:09:19.52276Z","end":"2026-09-01T05:09:20.100862Z","steps":["trace[338385586] 'read index received' (duration: 211.944668ms)","trace[338385586] 'applied index is now lower than readState.Index' (duration: 366.156403ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:09:20.1009Z","caller":"traceutil/trace.go:171","msg":"trace[1572249594] transaction","detail":"{read_only:false; response_revision:22317; number_of_response:1; }","duration":"605.097833ms","start":"2026-09-01T05:09:19.495756Z","end":"2026-09-01T05:09:20.100854Z","steps":["trace[1572249594] 'process raft request' (duration: 394.414148ms)","trace[1572249594] 'compare' (duration: 210.016927ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:09:20.100928Z","caller":"traceutil/trace.go:171","msg":"trace[1349465997] transaction","detail":"{read_only:false; response_revision:22319; number_of_response:1; }","duration":"601.775794ms","start":"2026-09-01T05:09:19.499124Z","end":"2026-09-01T05:09:20.1009Z","steps":["trace[1349465997] 'process raft request' (duration: 601.701383ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:09:20.100932Z","caller":"traceutil/trace.go:171","msg":"trace[869326213] transaction","detail":"{read_only:false; response_revision:22318; number_of_response:1; }","duration":"603.113769ms","start":"2026-09-01T05:09:19.497779Z","end":"2026-09-01T05:09:20.100893Z","steps":["trace[869326213] 'process raft request' (duration: 602.979156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:20.101054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:19.495737Z","time spent":"605.233575ms","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1369,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:09:20.10106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:19.4991Z","time spent":"601.890156ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:09:20.101106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:19.497752Z","time spent":"603.261923ms","remote":"127.0.0.1:59236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:09:20.101207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.609673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T05:09:20.101248Z","caller":"traceutil/trace.go:171","msg":"trace[1760881933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22319; }","duration":"225.685195ms","start":"2026-09-01T05:09:19.875553Z","end":"2026-09-01T05:09:20.101238Z","steps":["trace[1760881933] 'agreement among raft nodes before linearized reading' (duration: 225.620133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:20.101378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.873059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-09-01T05:09:20.101423Z","caller":"traceutil/trace.go:171","msg":"trace[2015661390] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22319; }","duration":"106.94849ms","start":"2026-09-01T05:09:19.994461Z","end":"2026-09-01T05:09:20.101409Z","steps":["trace[2015661390] 'agreement among raft nodes before linearized reading' (duration: 106.806876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:20.103229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.462224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-01T05:09:20.103294Z","caller":"traceutil/trace.go:171","msg":"trace[895682743] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:22319; }","duration":"580.545606ms","start":"2026-09-01T05:09:19.522722Z","end":"2026-09-01T05:09:20.103268Z","steps":["trace[895682743] 'agreement among raft nodes before linearized reading' (duration: 578.227913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:09:20.103329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:09:19.522676Z","time spent":"580.643278ms","remote":"127.0.0.1:59040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2181,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-09-01T05:12:10.666565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21438} {"level":"info","ts":"2026-09-01T05:12:10.79284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21438,"took":"122.896836ms","hash":4074172295,"current-db-size-bytes":49405952,"current-db-size":"49 MB","current-db-size-in-use-bytes":30597120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T05:12:10.792909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4074172295,"revision":21438,"compact-revision":19025} {"level":"warn","ts":"2026-09-01T05:13:21.485286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.530891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-09-01T05:13:21.485411Z","caller":"traceutil/trace.go:171","msg":"trace[210273414] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:24805; }","duration":"207.657944ms","start":"2026-09-01T05:13:21.27773Z","end":"2026-09-01T05:13:21.485388Z","steps":["trace[210273414] 'range keys from in-memory index tree' (duration: 207.320254ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:16:07.15659Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a75.wal"} {"level":"info","ts":"2026-09-01T05:17:10.673526Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23682} {"level":"info","ts":"2026-09-01T05:17:10.788291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23682,"took":"110.408171ms","hash":1083485949,"current-db-size-bytes":52477952,"current-db-size":"52 MB","current-db-size-in-use-bytes":42999808,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-01T05:17:10.78836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1083485949,"revision":23682,"compact-revision":21438} {"level":"info","ts":"2026-09-01T05:22:06.581131Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:22:06.588069Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T05:22:06.588165Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-01T05:22:10.680477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26831} {"level":"info","ts":"2026-09-01T05:22:10.822383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26831,"took":"138.281696ms","hash":1802906849,"current-db-size-bytes":52477952,"current-db-size":"52 MB","current-db-size-in-use-bytes":30232576,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-01T05:22:10.822451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1802906849,"revision":26831,"compact-revision":23682}