{"level":"warn","ts":"2026-09-01T04:16:37.801229Z","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:16:37.801338Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.93: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.93:2380","--initial-cluster=instance=https://162.253.55.93:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.93:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.93: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:16:37.801447Z","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:16:37.801462Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-01T04:16:37.801488Z","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:16:37.802268Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"]} {"level":"info","ts":"2026-09-01T04:16:37.80237Z","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.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.93: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:16:37.805375Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.739648ms"} {"level":"info","ts":"2026-09-01T04:16:37.813197Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ea06c5748459930b","cluster-id":"49d4e1d71c75bc5"} {"level":"info","ts":"2026-09-01T04:16:37.813331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T04:16:37.813358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 0"} {"level":"info","ts":"2026-09-01T04:16:37.813373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ea06c5748459930b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T04:16:37.813408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became follower at term 1"} {"level":"info","ts":"2026-09-01T04:16:37.813455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"warn","ts":"2026-09-01T04:16:37.824141Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T04:16:37.826943Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T04:16:37.8292Z","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:16:37.832455Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ea06c5748459930b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T04:16:37.832604Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ea06c5748459930b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T04:16:37.833517Z","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:16:37.833736Z","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:16:37.833895Z","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:16:37.834538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b switched to configuration voters=(16863382958962742027)"} {"level":"info","ts":"2026-09-01T04:16:37.834891Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","added-peer-id":"ea06c5748459930b","added-peer-peer-urls":["https://162.253.55.93:2380"]} {"level":"info","ts":"2026-09-01T04:16:37.83758Z","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:16:37.838335Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-01T04:16:37.838359Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.93:2380"} {"level":"info","ts":"2026-09-01T04:16:37.838822Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ea06c5748459930b","initial-advertise-peer-urls":["https://162.253.55.93:2380"],"listen-peer-urls":["https://162.253.55.93:2380"],"advertise-client-urls":["https://162.253.55.93:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.93:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T04:16:37.838907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T04:16:38.414153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T04:16:38.414218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T04:16:38.414287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgPreVoteResp from ea06c5748459930b at term 1"} {"level":"info","ts":"2026-09-01T04:16:38.414311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became candidate at term 2"} {"level":"info","ts":"2026-09-01T04:16:38.414326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b received MsgVoteResp from ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-01T04:16:38.414341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ea06c5748459930b became leader at term 2"} {"level":"info","ts":"2026-09-01T04:16:38.414358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ea06c5748459930b elected leader ea06c5748459930b at term 2"} {"level":"info","ts":"2026-09-01T04:16:38.415904Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:16:38.417114Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:16:38.417106Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ea06c5748459930b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.93:2379]}","request-path":"/0/members/ea06c5748459930b/attributes","cluster-id":"49d4e1d71c75bc5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T04:16:38.417182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T04:16:38.417524Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T04:16:38.417607Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T04:16:38.420899Z","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:16:38.421158Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49d4e1d71c75bc5","local-member-id":"ea06c5748459930b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:16:38.421158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.93:2379"} {"level":"info","ts":"2026-09-01T04:16:38.421259Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T04:16:38.421509Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-01T04:17:24.189216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.889358ms","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:17:24.189345Z","caller":"traceutil/trace.go:171","msg":"trace[1446474611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"191.130473ms","start":"2026-09-01T04:17:23.998187Z","end":"2026-09-01T04:17:24.189317Z","steps":["trace[1446474611] 'range keys from in-memory index tree' (duration: 190.556521ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:17:24.228484Z","caller":"traceutil/trace.go:171","msg":"trace[1916336117] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"158.257928ms","start":"2026-09-01T04:17:24.070194Z","end":"2026-09-01T04:17:24.228452Z","steps":["trace[1916336117] 'process raft request' (duration: 157.531163ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:17:24.713555Z","caller":"traceutil/trace.go:171","msg":"trace[429635119] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"405.635869ms","start":"2026-09-01T04:17:24.307881Z","end":"2026-09-01T04:17:24.713517Z","steps":["trace[429635119] 'process raft request' (duration: 405.418475ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:17:24.714433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:17:24.307851Z","time spent":"405.812932ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-01T04:17:24.714689Z","caller":"traceutil/trace.go:171","msg":"trace[1480511230] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"353.490679ms","start":"2026-09-01T04:17:24.361142Z","end":"2026-09-01T04:17:24.714632Z","steps":["trace[1480511230] 'process raft request' (duration: 353.0878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:17:24.714858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.826308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:17:24.715212Z","caller":"traceutil/trace.go:171","msg":"trace[599761871] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"321.41742ms","start":"2026-09-01T04:17:24.393026Z","end":"2026-09-01T04:17:24.714443Z","steps":["trace[599761871] 'read index received' (duration: 320.425959ms)","trace[599761871] 'applied index is now lower than readState.Index' (duration: 990.071µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:17:24.715268Z","caller":"traceutil/trace.go:171","msg":"trace[522268294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"322.192297ms","start":"2026-09-01T04:17:24.393006Z","end":"2026-09-01T04:17:24.715198Z","steps":["trace[522268294] 'agreement among raft nodes before linearized reading' (duration: 321.728086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:17:24.715405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:17:24.392922Z","time spent":"322.420712ms","remote":"127.0.0.1:47392","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:17:24.715584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:17:24.361107Z","time spent":"353.846097ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:17:24.71568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.214407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-01T04:17:24.715773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.797245ms","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:17:24.715809Z","caller":"traceutil/trace.go:171","msg":"trace[2101252130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"107.855286ms","start":"2026-09-01T04:17:24.607946Z","end":"2026-09-01T04:17:24.715801Z","steps":["trace[2101252130] 'agreement among raft nodes before linearized reading' (duration: 107.709153ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:17:24.715923Z","caller":"traceutil/trace.go:171","msg":"trace[937241854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"117.438702ms","start":"2026-09-01T04:17:24.598283Z","end":"2026-09-01T04:17:24.715722Z","steps":["trace[937241854] 'agreement among raft nodes before linearized reading' (duration: 117.134155ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:19:16.591825Z","caller":"traceutil/trace.go:171","msg":"trace[1140727169] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"178.500592ms","start":"2026-09-01T04:19:16.413309Z","end":"2026-09-01T04:19:16.591809Z","steps":["trace[1140727169] 'process raft request' (duration: 178.307368ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:19:16.701253Z","caller":"traceutil/trace.go:171","msg":"trace[1453617841] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"102.762697ms","start":"2026-09-01T04:19:16.598451Z","end":"2026-09-01T04:19:16.701213Z","steps":["trace[1453617841] 'process raft request' (duration: 102.578333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:19:17.02944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.335919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d1177d5d718012\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-01T04:19:17.029548Z","caller":"traceutil/trace.go:171","msg":"trace[222449140] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d1177d5d718012; range_end:; response_count:1; response_revision:1907; }","duration":"178.537073ms","start":"2026-09-01T04:19:16.850989Z","end":"2026-09-01T04:19:17.029526Z","steps":["trace[222449140] 'range keys from in-memory index tree' (duration: 178.185405ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:19:17.029591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.902502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-01T04:19:17.029613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.282591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-01T04:19:17.0297Z","caller":"traceutil/trace.go:171","msg":"trace[390028909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1907; }","duration":"166.077445ms","start":"2026-09-01T04:19:16.8636Z","end":"2026-09-01T04:19:17.029677Z","steps":["trace[390028909] 'range keys from in-memory index tree' (duration: 165.676037ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:19:17.029738Z","caller":"traceutil/trace.go:171","msg":"trace[1420383164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1907; }","duration":"228.463444ms","start":"2026-09-01T04:19:16.801245Z","end":"2026-09-01T04:19:17.029708Z","steps":["trace[1420383164] 'range keys from in-memory index tree' (duration: 228.020945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:19:17.030136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.052922ms","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:19:17.030187Z","caller":"traceutil/trace.go:171","msg":"trace[676295649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1907; }","duration":"157.179035ms","start":"2026-09-01T04:19:16.872993Z","end":"2026-09-01T04:19:17.030172Z","steps":["trace[676295649] 'range keys from in-memory index tree' (duration: 156.00782ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:19:33.302798Z","caller":"traceutil/trace.go:171","msg":"trace[1847631301] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"166.215628ms","start":"2026-09-01T04:19:33.136564Z","end":"2026-09-01T04:19:33.302779Z","steps":["trace[1847631301] 'read index received' (duration: 166.109275ms)","trace[1847631301] 'applied index is now lower than readState.Index' (duration: 105.733µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:19:33.302924Z","caller":"traceutil/trace.go:171","msg":"trace[2043850215] transaction","detail":"{read_only:false; response_revision:2044; number_of_response:1; }","duration":"217.420467ms","start":"2026-09-01T04:19:33.085473Z","end":"2026-09-01T04:19:33.302893Z","steps":["trace[2043850215] 'process raft request' (duration: 217.167882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:19:33.30298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.400252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-01T04:19:33.303Z","caller":"traceutil/trace.go:171","msg":"trace[905646670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2044; }","duration":"166.479514ms","start":"2026-09-01T04:19:33.136515Z","end":"2026-09-01T04:19:33.302995Z","steps":["trace[905646670] 'agreement among raft nodes before linearized reading' (duration: 166.372572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:19:33.303107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.080944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-01T04:19:33.303119Z","caller":"traceutil/trace.go:171","msg":"trace[906914344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2044; }","duration":"165.133576ms","start":"2026-09-01T04:19:33.137982Z","end":"2026-09-01T04:19:33.303115Z","steps":["trace[906914344] 'agreement among raft nodes before linearized reading' (duration: 165.095715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:19:33.303194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.126613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-01T04:19:33.303229Z","caller":"traceutil/trace.go:171","msg":"trace[1003189320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2044; }","duration":"164.191865ms","start":"2026-09-01T04:19:33.139026Z","end":"2026-09-01T04:19:33.303218Z","steps":["trace[1003189320] 'agreement among raft nodes before linearized reading' (duration: 164.063262ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:21:21.8209Z","caller":"traceutil/trace.go:171","msg":"trace[242698474] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"128.058847ms","start":"2026-09-01T04:21:21.692812Z","end":"2026-09-01T04:21:21.820871Z","steps":["trace[242698474] 'process raft request' (duration: 127.861162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:25:59.860932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.307817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:25:59.861051Z","caller":"traceutil/trace.go:171","msg":"trace[1614243350] linearizableReadLoop","detail":"{readStateIndex:5388; appliedIndex:5387; }","duration":"100.036324ms","start":"2026-09-01T04:25:59.760997Z","end":"2026-09-01T04:25:59.861033Z","steps":["trace[1614243350] 'read index received' (duration: 77.502µs)","trace[1614243350] 'applied index is now lower than readState.Index' (duration: 99.957111ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:25:59.861131Z","caller":"traceutil/trace.go:171","msg":"trace[1062057875] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"173.833113ms","start":"2026-09-01T04:25:59.687259Z","end":"2026-09-01T04:25:59.861092Z","steps":["trace[1062057875] 'process raft request' (duration: 67.119618ms)","trace[1062057875] 'compare' (duration: 106.186014ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:25:59.861243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.225727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-049d5c49-2e3e-4b61-a454-f7aa8529d928\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-09-01T04:25:59.861293Z","caller":"traceutil/trace.go:171","msg":"trace[928096061] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-049d5c49-2e3e-4b61-a454-f7aa8529d928; range_end:; response_count:1; response_revision:5185; }","duration":"100.298409ms","start":"2026-09-01T04:25:59.760978Z","end":"2026-09-01T04:25:59.861276Z","steps":["trace[928096061] 'agreement among raft nodes before linearized reading' (duration: 100.098954ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:25:59.915009Z","caller":"traceutil/trace.go:171","msg":"trace[1403604215] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"123.376648ms","start":"2026-09-01T04:25:59.791604Z","end":"2026-09-01T04:25:59.914981Z","steps":["trace[1403604215] 'process raft request' (duration: 123.192373ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:26:24.760549Z","caller":"traceutil/trace.go:171","msg":"trace[825190474] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"204.388329ms","start":"2026-09-01T04:26:24.55614Z","end":"2026-09-01T04:26:24.760528Z","steps":["trace[825190474] 'process raft request' (duration: 204.270637ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:26:38.453498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2929} {"level":"info","ts":"2026-09-01T04:26:38.563513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2929,"took":"108.314823ms","hash":1101543123,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":32223232,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T04:26:38.563661Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101543123,"revision":2929,"compact-revision":-1} {"level":"info","ts":"2026-09-01T04:26:45.479316Z","caller":"traceutil/trace.go:171","msg":"trace[2109296883] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"364.919815ms","start":"2026-09-01T04:26:45.114371Z","end":"2026-09-01T04:26:45.479291Z","steps":["trace[2109296883] 'process raft request' (duration: 364.75416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:26:45.479468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:26:45.114352Z","time spent":"365.039937ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:26:45.603482Z","caller":"traceutil/trace.go:171","msg":"trace[685189574] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"473.82435ms","start":"2026-09-01T04:26:45.129626Z","end":"2026-09-01T04:26:45.60345Z","steps":["trace[685189574] 'process raft request' (duration: 473.634256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:26:45.603636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:26:45.129599Z","time spent":"473.961672ms","remote":"127.0.0.1:47682","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:26:45.603841Z","caller":"traceutil/trace.go:171","msg":"trace[1453739682] transaction","detail":"{read_only:false; response_revision:5851; number_of_response:1; }","duration":"406.577758ms","start":"2026-09-01T04:26:45.197243Z","end":"2026-09-01T04:26:45.603821Z","steps":["trace[1453739682] 'process raft request' (duration: 406.168029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:26:45.603938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:26:45.197219Z","time spent":"406.670059ms","remote":"127.0.0.1:47682","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:26:45.603895Z","caller":"traceutil/trace.go:171","msg":"trace[666180085] transaction","detail":"{read_only:false; response_revision:5852; number_of_response:1; }","duration":"208.651393ms","start":"2026-09-01T04:26:45.395214Z","end":"2026-09-01T04:26:45.603866Z","steps":["trace[666180085] 'process raft request' (duration: 208.48018ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:26:45.603939Z","caller":"traceutil/trace.go:171","msg":"trace[1393510723] linearizableReadLoop","detail":"{readStateIndex:6072; appliedIndex:6070; }","duration":"421.073188ms","start":"2026-09-01T04:26:45.182837Z","end":"2026-09-01T04:26:45.603911Z","steps":["trace[1393510723] 'read index received' (duration: 296.320283ms)","trace[1393510723] 'applied index is now lower than readState.Index' (duration: 124.751795ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:26:45.604063Z","caller":"traceutil/trace.go:171","msg":"trace[640768727] transaction","detail":"{read_only:false; response_revision:5853; number_of_response:1; }","duration":"202.204865ms","start":"2026-09-01T04:26:45.401848Z","end":"2026-09-01T04:26:45.604052Z","steps":["trace[640768727] 'process raft request' (duration: 201.974661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:26:45.604202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.356134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-01T04:26:45.604246Z","caller":"traceutil/trace.go:171","msg":"trace[2071909353] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5853; }","duration":"421.446996ms","start":"2026-09-01T04:26:45.182787Z","end":"2026-09-01T04:26:45.604234Z","steps":["trace[2071909353] 'agreement among raft nodes before linearized reading' (duration: 421.261042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:26:45.60427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:26:45.182765Z","time spent":"421.498467ms","remote":"127.0.0.1:47562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-01T04:26:45.746069Z","caller":"traceutil/trace.go:171","msg":"trace[210455079] transaction","detail":"{read_only:false; response_revision:5855; number_of_response:1; }","duration":"260.056786ms","start":"2026-09-01T04:26:45.485982Z","end":"2026-09-01T04:26:45.746039Z","steps":["trace[210455079] 'process raft request' (duration: 259.7832ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:26:45.746089Z","caller":"traceutil/trace.go:171","msg":"trace[1395653496] transaction","detail":"{read_only:false; response_revision:5856; number_of_response:1; }","duration":"259.973064ms","start":"2026-09-01T04:26:45.486093Z","end":"2026-09-01T04:26:45.746066Z","steps":["trace[1395653496] 'process raft request' (duration: 259.790531ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:26:45.746423Z","caller":"traceutil/trace.go:171","msg":"trace[1365031911] transaction","detail":"{read_only:false; response_revision:5854; number_of_response:1; }","duration":"260.543836ms","start":"2026-09-01T04:26:45.485677Z","end":"2026-09-01T04:26:45.746221Z","steps":["trace[1365031911] 'process raft request' (duration: 248.623701ms)","trace[1365031911] 'compare' (duration: 11.285901ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:26:45.746501Z","caller":"traceutil/trace.go:171","msg":"trace[1859648799] linearizableReadLoop","detail":"{readStateIndex:6080; appliedIndex:6075; }","duration":"142.479395ms","start":"2026-09-01T04:26:45.603997Z","end":"2026-09-01T04:26:45.746476Z","steps":["trace[1859648799] 'read index received' (duration: 130.224043ms)","trace[1859648799] 'applied index is now lower than readState.Index' (duration: 12.254342ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:26:45.746503Z","caller":"traceutil/trace.go:171","msg":"trace[1003736148] transaction","detail":"{read_only:false; response_revision:5857; number_of_response:1; }","duration":"259.138617ms","start":"2026-09-01T04:26:45.487327Z","end":"2026-09-01T04:26:45.746465Z","steps":["trace[1003736148] 'process raft request' (duration: 258.610966ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:26:45.746624Z","caller":"traceutil/trace.go:171","msg":"trace[390652388] transaction","detail":"{read_only:false; response_revision:5858; number_of_response:1; }","duration":"172.288545ms","start":"2026-09-01T04:26:45.574314Z","end":"2026-09-01T04:26:45.746602Z","steps":["trace[390652388] 'process raft request' (duration: 172.039249ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:26:45.746695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.072091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:26:45.746765Z","caller":"traceutil/trace.go:171","msg":"trace[1052189073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5858; }","duration":"354.181103ms","start":"2026-09-01T04:26:45.392571Z","end":"2026-09-01T04:26:45.746752Z","steps":["trace[1052189073] 'agreement among raft nodes before linearized reading' (duration: 354.04185ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:26:45.746799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:26:45.392557Z","time spent":"354.232525ms","remote":"127.0.0.1:47404","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:26:45.746919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.488841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-75e2d2c0-dee8-4af8-8005-f0fa20cf07a4\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-01T04:26:45.74698Z","caller":"traceutil/trace.go:171","msg":"trace[1590417592] range","detail":"{range_begin:/registry/persistentvolumes/pvc-75e2d2c0-dee8-4af8-8005-f0fa20cf07a4; range_end:; response_count:1; response_revision:5858; }","duration":"139.583552ms","start":"2026-09-01T04:26:45.607376Z","end":"2026-09-01T04:26:45.74696Z","steps":["trace[1590417592] 'agreement among raft nodes before linearized reading' (duration: 139.392968ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:27:33.914858Z","caller":"traceutil/trace.go:171","msg":"trace[1762761621] transaction","detail":"{read_only:false; response_revision:6263; number_of_response:1; }","duration":"129.652709ms","start":"2026-09-01T04:27:33.785184Z","end":"2026-09-01T04:27:33.914837Z","steps":["trace[1762761621] 'process raft request' (duration: 112.394089ms)","trace[1762761621] 'compare' (duration: 17.046456ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:27:33.915241Z","caller":"traceutil/trace.go:171","msg":"trace[496310507] transaction","detail":"{read_only:false; response_revision:6264; number_of_response:1; }","duration":"126.760798ms","start":"2026-09-01T04:27:33.788453Z","end":"2026-09-01T04:27:33.915214Z","steps":["trace[496310507] 'process raft request' (duration: 126.328038ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:27:33.915304Z","caller":"traceutil/trace.go:171","msg":"trace[1890206270] transaction","detail":"{read_only:false; response_revision:6265; number_of_response:1; }","duration":"126.788949ms","start":"2026-09-01T04:27:33.788506Z","end":"2026-09-01T04:27:33.915295Z","steps":["trace[1890206270] 'process raft request' (duration: 126.490843ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:27:33.915417Z","caller":"traceutil/trace.go:171","msg":"trace[299537466] transaction","detail":"{read_only:false; response_revision:6266; number_of_response:1; }","duration":"123.14019ms","start":"2026-09-01T04:27:33.792262Z","end":"2026-09-01T04:27:33.915402Z","steps":["trace[299537466] 'process raft request' (duration: 123.000557ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:31:38.467955Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5794} {"level":"info","ts":"2026-09-01T04:31:38.596734Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5794,"took":"127.138149ms","hash":893843418,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":26230784,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T04:31:38.596806Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":893843418,"revision":5794,"compact-revision":2929} {"level":"info","ts":"2026-09-01T04:33:19.47566Z","caller":"traceutil/trace.go:171","msg":"trace[357956344] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"121.229894ms","start":"2026-09-01T04:33:19.354411Z","end":"2026-09-01T04:33:19.475641Z","steps":["trace[357956344] 'process raft request' (duration: 87.384957ms)","trace[357956344] 'compare' (duration: 33.560521ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:33:19.475842Z","caller":"traceutil/trace.go:171","msg":"trace[1141340154] transaction","detail":"{read_only:false; response_revision:8750; number_of_response:1; }","duration":"118.499505ms","start":"2026-09-01T04:33:19.357323Z","end":"2026-09-01T04:33:19.475823Z","steps":["trace[1141340154] 'process raft request' (duration: 118.160998ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:33:32.348255Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023b7.wal"} {"level":"info","ts":"2026-09-01T04:33:47.176876Z","caller":"traceutil/trace.go:171","msg":"trace[1955714256] linearizableReadLoop","detail":"{readStateIndex:9274; appliedIndex:9273; }","duration":"105.31668ms","start":"2026-09-01T04:33:47.071529Z","end":"2026-09-01T04:33:47.176846Z","steps":["trace[1955714256] 'read index received' (duration: 13.435287ms)","trace[1955714256] 'applied index is now lower than readState.Index' (duration: 91.879883ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:33:47.177008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.456023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/rook-ceph.cephfs.csi.ceph.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:33:47.177042Z","caller":"traceutil/trace.go:171","msg":"trace[1168395103] range","detail":"{range_begin:/registry/csidrivers/rook-ceph.cephfs.csi.ceph.com; range_end:; response_count:0; response_revision:8949; }","duration":"105.549535ms","start":"2026-09-01T04:33:47.071481Z","end":"2026-09-01T04:33:47.17703Z","steps":["trace[1168395103] 'agreement among raft nodes before linearized reading' (duration: 105.466734ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:34:01.225673Z","caller":"traceutil/trace.go:171","msg":"trace[2032250199] transaction","detail":"{read_only:false; response_revision:9026; number_of_response:1; }","duration":"101.561191ms","start":"2026-09-01T04:34:01.124072Z","end":"2026-09-01T04:34:01.225634Z","steps":["trace[2032250199] 'process raft request' (duration: 101.180813ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:34:08.487047Z","caller":"traceutil/trace.go:171","msg":"trace[1738667319] linearizableReadLoop","detail":"{readStateIndex:9431; appliedIndex:9429; }","duration":"187.887433ms","start":"2026-09-01T04:34:08.299133Z","end":"2026-09-01T04:34:08.48702Z","steps":["trace[1738667319] 'read index received' (duration: 49.84504ms)","trace[1738667319] 'applied index is now lower than readState.Index' (duration: 138.040613ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:34:08.487093Z","caller":"traceutil/trace.go:171","msg":"trace[1472723029] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9100; }","duration":"191.364388ms","start":"2026-09-01T04:34:08.295704Z","end":"2026-09-01T04:34:08.487069Z","steps":["trace[1472723029] 'process raft request' (duration: 191.199845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:34:08.487244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.092958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-01T04:34:08.487304Z","caller":"traceutil/trace.go:171","msg":"trace[330465624] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:9100; }","duration":"188.20645ms","start":"2026-09-01T04:34:08.299083Z","end":"2026-09-01T04:34:08.487289Z","steps":["trace[330465624] 'agreement among raft nodes before linearized reading' (duration: 188.028256ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:34:08.499569Z","caller":"traceutil/trace.go:171","msg":"trace[15605044] transaction","detail":"{read_only:false; response_revision:9101; number_of_response:1; }","duration":"144.073534ms","start":"2026-09-01T04:34:08.355472Z","end":"2026-09-01T04:34:08.499545Z","steps":["trace[15605044] 'process raft request' (duration: 143.571524ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:34:08.499662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.450803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs-thst2\" ","response":"range_response_count:1 size:2498"} {"level":"info","ts":"2026-09-01T04:34:08.499782Z","caller":"traceutil/trace.go:171","msg":"trace[835904944] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs-thst2; range_end:; response_count:1; response_revision:9101; }","duration":"145.644187ms","start":"2026-09-01T04:34:08.35411Z","end":"2026-09-01T04:34:08.499754Z","steps":["trace[835904944] 'agreement among raft nodes before linearized reading' (duration: 145.253708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:34:08.500095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.469468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-09-01T04:34:08.500158Z","caller":"traceutil/trace.go:171","msg":"trace[87240404] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:9101; }","duration":"196.578211ms","start":"2026-09-01T04:34:08.303566Z","end":"2026-09-01T04:34:08.500144Z","steps":["trace[87240404] 'agreement among raft nodes before linearized reading' (duration: 196.416177ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:34:08.502898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.992008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-09-01T04:34:08.502975Z","caller":"traceutil/trace.go:171","msg":"trace[1122754802] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:9101; }","duration":"115.111121ms","start":"2026-09-01T04:34:08.38785Z","end":"2026-09-01T04:34:08.502961Z","steps":["trace[1122754802] 'agreement among raft nodes before linearized reading' (duration: 111.492834ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:34:08.815132Z","caller":"traceutil/trace.go:171","msg":"trace[1340477876] transaction","detail":"{read_only:false; response_revision:9105; number_of_response:1; }","duration":"199.115485ms","start":"2026-09-01T04:34:08.615984Z","end":"2026-09-01T04:34:08.815099Z","steps":["trace[1340477876] 'process raft request' (duration: 146.600669ms)","trace[1340477876] 'compare' (duration: 52.162699ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:34:08.815343Z","caller":"traceutil/trace.go:171","msg":"trace[1745257813] transaction","detail":"{read_only:false; response_revision:9106; number_of_response:1; }","duration":"197.54029ms","start":"2026-09-01T04:34:08.617766Z","end":"2026-09-01T04:34:08.815306Z","steps":["trace[1745257813] 'process raft request' (duration: 197.270445ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:34:08.81543Z","caller":"traceutil/trace.go:171","msg":"trace[1597321601] transaction","detail":"{read_only:false; response_revision:9107; number_of_response:1; }","duration":"197.51358ms","start":"2026-09-01T04:34:08.617903Z","end":"2026-09-01T04:34:08.815417Z","steps":["trace[1597321601] 'process raft request' (duration: 197.273775ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:34:08.815555Z","caller":"traceutil/trace.go:171","msg":"trace[1343211149] transaction","detail":"{read_only:false; response_revision:9108; number_of_response:1; }","duration":"191.704306ms","start":"2026-09-01T04:34:08.623822Z","end":"2026-09-01T04:34:08.815526Z","steps":["trace[1343211149] 'process raft request' (duration: 191.400659ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:34:08.815381Z","caller":"traceutil/trace.go:171","msg":"trace[368491307] linearizableReadLoop","detail":"{readStateIndex:9440; appliedIndex:9436; }","duration":"130.806657ms","start":"2026-09-01T04:34:08.684544Z","end":"2026-09-01T04:34:08.81535Z","steps":["trace[368491307] 'read index received' (duration: 77.976804ms)","trace[368491307] 'applied index is now lower than readState.Index' (duration: 52.828243ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:34:08.815622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.480526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-09-01T04:34:08.815724Z","caller":"traceutil/trace.go:171","msg":"trace[66376939] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9108; }","duration":"167.653409ms","start":"2026-09-01T04:34:08.648052Z","end":"2026-09-01T04:34:08.815705Z","steps":["trace[66376939] 'agreement among raft nodes before linearized reading' (duration: 167.394682ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:34:08.815693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.897662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-01T04:34:08.815789Z","caller":"traceutil/trace.go:171","msg":"trace[1369126885] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:9108; }","duration":"139.046215ms","start":"2026-09-01T04:34:08.676717Z","end":"2026-09-01T04:34:08.815763Z","steps":["trace[1369126885] 'agreement among raft nodes before linearized reading' (duration: 138.863161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:34:08.815946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.730762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-01T04:34:08.816003Z","caller":"traceutil/trace.go:171","msg":"trace[782345809] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:9108; }","duration":"128.08931ms","start":"2026-09-01T04:34:08.687893Z","end":"2026-09-01T04:34:08.815983Z","steps":["trace[782345809] 'agreement among raft nodes before linearized reading' (duration: 127.864945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:34:08.816032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.285167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-01T04:34:08.816087Z","caller":"traceutil/trace.go:171","msg":"trace[1190452611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9108; }","duration":"157.459082ms","start":"2026-09-01T04:34:08.658611Z","end":"2026-09-01T04:34:08.81607Z","steps":["trace[1190452611] 'agreement among raft nodes before linearized reading' (duration: 157.332019ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:35:06.302888Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T04:35:06.319781Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-01T04:35:06.319888Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-01T04:36:38.477881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7982} {"level":"info","ts":"2026-09-01T04:36:38.551267Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7982,"took":"71.297895ms","hash":2752421375,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":27865088,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T04:36:38.551339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2752421375,"revision":7982,"compact-revision":5794} {"level":"info","ts":"2026-09-01T04:41:15.267405Z","caller":"traceutil/trace.go:171","msg":"trace[309861348] linearizableReadLoop","detail":"{readStateIndex:12783; appliedIndex:12782; }","duration":"124.359559ms","start":"2026-09-01T04:41:15.143021Z","end":"2026-09-01T04:41:15.267381Z","steps":["trace[309861348] 'read index received' (duration: 124.106434ms)","trace[309861348] 'applied index is now lower than readState.Index' (duration: 252.145µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:41:15.267474Z","caller":"traceutil/trace.go:171","msg":"trace[2055753203] transaction","detail":"{read_only:false; response_revision:12350; number_of_response:1; }","duration":"163.218968ms","start":"2026-09-01T04:41:15.104233Z","end":"2026-09-01T04:41:15.267452Z","steps":["trace[2055753203] 'process raft request' (duration: 162.998703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:41:15.26763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.590465ms","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:41:15.267685Z","caller":"traceutil/trace.go:171","msg":"trace[1224279517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12350; }","duration":"124.689827ms","start":"2026-09-01T04:41:15.142985Z","end":"2026-09-01T04:41:15.267675Z","steps":["trace[1224279517] 'agreement among raft nodes before linearized reading' (duration: 124.515383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:41:15.267803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.536494ms","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:41:15.267874Z","caller":"traceutil/trace.go:171","msg":"trace[754828492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12350; }","duration":"124.663017ms","start":"2026-09-01T04:41:15.143196Z","end":"2026-09-01T04:41:15.267859Z","steps":["trace[754828492] 'agreement among raft nodes before linearized reading' (duration: 124.428772ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:15.401615Z","caller":"traceutil/trace.go:171","msg":"trace[1842227704] transaction","detail":"{read_only:false; response_revision:12351; number_of_response:1; }","duration":"129.514651ms","start":"2026-09-01T04:41:15.27205Z","end":"2026-09-01T04:41:15.401564Z","steps":["trace[1842227704] 'process raft request' (duration: 125.847703ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:15.407106Z","caller":"traceutil/trace.go:171","msg":"trace[450526190] transaction","detail":"{read_only:false; response_revision:12352; number_of_response:1; }","duration":"134.261483ms","start":"2026-09-01T04:41:15.272816Z","end":"2026-09-01T04:41:15.407077Z","steps":["trace[450526190] 'process raft request' (duration: 133.946217ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:15.407358Z","caller":"traceutil/trace.go:171","msg":"trace[997019499] transaction","detail":"{read_only:false; response_revision:12353; number_of_response:1; }","duration":"133.519247ms","start":"2026-09-01T04:41:15.273815Z","end":"2026-09-01T04:41:15.407334Z","steps":["trace[997019499] 'process raft request' (duration: 133.20647ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:15.407533Z","caller":"traceutil/trace.go:171","msg":"trace[2095900133] transaction","detail":"{read_only:false; response_revision:12354; number_of_response:1; }","duration":"125.985116ms","start":"2026-09-01T04:41:15.281533Z","end":"2026-09-01T04:41:15.407518Z","steps":["trace[2095900133] 'process raft request' (duration: 125.6998ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:41:38.4852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10359} {"level":"info","ts":"2026-09-01T04:41:38.575864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10359,"took":"88.347324ms","hash":2242343576,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":25882624,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-01T04:41:38.575977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2242343576,"revision":10359,"compact-revision":7982} {"level":"info","ts":"2026-09-01T04:46:38.493044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12526} {"level":"info","ts":"2026-09-01T04:46:38.572923Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12526,"took":"77.447389ms","hash":881439978,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":27394048,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-01T04:46:38.57299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":881439978,"revision":12526,"compact-revision":10359} {"level":"info","ts":"2026-09-01T04:47:37.657152Z","caller":"traceutil/trace.go:171","msg":"trace[1121993054] linearizableReadLoop","detail":"{readStateIndex:15439; appliedIndex:15438; }","duration":"134.232411ms","start":"2026-09-01T04:47:37.52289Z","end":"2026-09-01T04:47:37.657123Z","steps":["trace[1121993054] 'read index received' (duration: 134.048237ms)","trace[1121993054] 'applied index is now lower than readState.Index' (duration: 182.764µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:47:37.657222Z","caller":"traceutil/trace.go:171","msg":"trace[942991636] transaction","detail":"{read_only:false; response_revision:14916; number_of_response:1; }","duration":"140.812053ms","start":"2026-09-01T04:47:37.516381Z","end":"2026-09-01T04:47:37.657193Z","steps":["trace[942991636] 'process raft request' (duration: 140.547647ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:47:37.657484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.584369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-01T04:47:37.657541Z","caller":"traceutil/trace.go:171","msg":"trace[483058901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14916; }","duration":"134.690931ms","start":"2026-09-01T04:47:37.522838Z","end":"2026-09-01T04:47:37.657529Z","steps":["trace[483058901] 'agreement among raft nodes before linearized reading' (duration: 134.452596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:08.051284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.952986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T04:48:08.052096Z","caller":"traceutil/trace.go:171","msg":"trace[290892244] transaction","detail":"{read_only:false; response_revision:15073; number_of_response:1; }","duration":"194.95146ms","start":"2026-09-01T04:48:07.857116Z","end":"2026-09-01T04:48:08.052067Z","steps":["trace[290892244] 'process raft request' (duration: 15.081155ms)","trace[290892244] 'compare' (duration: 178.609018ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:48:08.052693Z","caller":"traceutil/trace.go:171","msg":"trace[130410813] transaction","detail":"{read_only:false; response_revision:15074; number_of_response:1; }","duration":"195.446872ms","start":"2026-09-01T04:48:07.857182Z","end":"2026-09-01T04:48:08.052629Z","steps":["trace[130410813] 'process raft request' (duration: 194.257525ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:17.28808Z","caller":"traceutil/trace.go:171","msg":"trace[436915278] transaction","detail":"{read_only:false; response_revision:15131; number_of_response:1; }","duration":"160.139121ms","start":"2026-09-01T04:48:17.127898Z","end":"2026-09-01T04:48:17.288037Z","steps":["trace[436915278] 'process raft request' (duration: 159.804963ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:17.373047Z","caller":"traceutil/trace.go:171","msg":"trace[306097442] linearizableReadLoop","detail":"{readStateIndex:15663; appliedIndex:15661; }","duration":"224.942818ms","start":"2026-09-01T04:48:17.148059Z","end":"2026-09-01T04:48:17.373002Z","steps":["trace[306097442] 'read index received' (duration: 56.644762ms)","trace[306097442] 'applied index is now lower than readState.Index' (duration: 168.296716ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:48:17.373299Z","caller":"traceutil/trace.go:171","msg":"trace[733050323] transaction","detail":"{read_only:false; response_revision:15132; number_of_response:1; }","duration":"120.980007ms","start":"2026-09-01T04:48:17.252279Z","end":"2026-09-01T04:48:17.373259Z","steps":["trace[733050323] 'process raft request' (duration: 120.539658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:17.375515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.43044ms","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:48:17.375646Z","caller":"traceutil/trace.go:171","msg":"trace[741251943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15132; }","duration":"227.600574ms","start":"2026-09-01T04:48:17.148022Z","end":"2026-09-01T04:48:17.375623Z","steps":["trace[741251943] 'agreement among raft nodes before linearized reading' (duration: 225.225643ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:17.375829Z","caller":"traceutil/trace.go:171","msg":"trace[2011600328] transaction","detail":"{read_only:false; response_revision:15133; number_of_response:1; }","duration":"122.815466ms","start":"2026-09-01T04:48:17.252991Z","end":"2026-09-01T04:48:17.375807Z","steps":["trace[2011600328] 'process raft request' (duration: 120.22474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:17.375962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.471724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-m4r79\" ","response":"range_response_count:1 size:11624"} {"level":"info","ts":"2026-09-01T04:48:17.376039Z","caller":"traceutil/trace.go:171","msg":"trace[990052512] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-m4r79; range_end:; response_count:1; response_revision:15133; }","duration":"196.599387ms","start":"2026-09-01T04:48:17.179419Z","end":"2026-09-01T04:48:17.376018Z","steps":["trace[990052512] 'agreement among raft nodes before linearized reading' (duration: 196.324141ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:27.7356Z","caller":"traceutil/trace.go:171","msg":"trace[949115809] linearizableReadLoop","detail":"{readStateIndex:15840; appliedIndex:15838; }","duration":"296.340705ms","start":"2026-09-01T04:48:27.439206Z","end":"2026-09-01T04:48:27.735547Z","steps":["trace[949115809] 'read index received' (duration: 293.986615ms)","trace[949115809] 'applied index is now lower than readState.Index' (duration: 2.35251ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:48:27.735725Z","caller":"traceutil/trace.go:171","msg":"trace[82114467] transaction","detail":"{read_only:false; response_revision:15304; number_of_response:1; }","duration":"321.546958ms","start":"2026-09-01T04:48:27.414133Z","end":"2026-09-01T04:48:27.73568Z","steps":["trace[82114467] 'process raft request' (duration: 321.265212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:27.735906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.108495ms","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:48:27.735954Z","caller":"traceutil/trace.go:171","msg":"trace[737279530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15304; }","duration":"312.206977ms","start":"2026-09-01T04:48:27.423735Z","end":"2026-09-01T04:48:27.735942Z","steps":["trace[737279530] 'agreement among raft nodes before linearized reading' (duration: 311.999092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:27.735985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:48:27.423718Z","time spent":"312.258719ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-01T04:48:27.735975Z","caller":"traceutil/trace.go:171","msg":"trace[2071032436] transaction","detail":"{read_only:false; response_revision:15303; number_of_response:1; }","duration":"332.438343ms","start":"2026-09-01T04:48:27.403478Z","end":"2026-09-01T04:48:27.735916Z","steps":["trace[2071032436] 'process raft request' (duration: 329.704774ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:27.736154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.95067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2ee072dd-111b-4a83-ad84-ca2134c95b71\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-09-01T04:48:27.736295Z","caller":"traceutil/trace.go:171","msg":"trace[48261965] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2ee072dd-111b-4a83-ad84-ca2134c95b71; range_end:; response_count:1; response_revision:15304; }","duration":"343.136723ms","start":"2026-09-01T04:48:27.393108Z","end":"2026-09-01T04:48:27.736244Z","steps":["trace[48261965] 'agreement among raft nodes before linearized reading' (duration: 342.827687ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:27.736374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:48:27.393077Z","time spent":"343.256706ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3629,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2ee072dd-111b-4a83-ad84-ca2134c95b71\" "} {"level":"warn","ts":"2026-09-01T04:48:27.736621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:48:27.403448Z","time spent":"332.689628ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T04:48:27.737482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.258714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T04:48:27.737582Z","caller":"traceutil/trace.go:171","msg":"trace[1351681319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15305; }","duration":"297.417548ms","start":"2026-09-01T04:48:27.440145Z","end":"2026-09-01T04:48:27.737563Z","steps":["trace[1351681319] 'agreement among raft nodes before linearized reading' (duration: 297.224394ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:48:27.737605Z","caller":"traceutil/trace.go:171","msg":"trace[1653255664] transaction","detail":"{read_only:false; response_revision:15305; number_of_response:1; }","duration":"103.116551ms","start":"2026-09-01T04:48:27.634472Z","end":"2026-09-01T04:48:27.737589Z","steps":["trace[1653255664] 'process raft request' (duration: 102.787114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:48:27.741796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:48:27.414112Z","time spent":"321.727042ms","remote":"127.0.0.1:47682","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:50:16.393098Z","caller":"traceutil/trace.go:171","msg":"trace[1987811844] linearizableReadLoop","detail":"{readStateIndex:17001; appliedIndex:17000; }","duration":"217.307452ms","start":"2026-09-01T04:50:16.175769Z","end":"2026-09-01T04:50:16.393076Z","steps":["trace[1987811844] 'read index received' (duration: 217.260751ms)","trace[1987811844] 'applied index is now lower than readState.Index' (duration: 45.691µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:50:16.39321Z","caller":"traceutil/trace.go:171","msg":"trace[1517701752] transaction","detail":"{read_only:false; response_revision:16430; number_of_response:1; }","duration":"218.266313ms","start":"2026-09-01T04:50:16.174906Z","end":"2026-09-01T04:50:16.393173Z","steps":["trace[1517701752] 'process raft request' (duration: 218.006618ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:50:16.393297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.507228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-01T04:50:16.393361Z","caller":"traceutil/trace.go:171","msg":"trace[675857010] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16430; }","duration":"217.584119ms","start":"2026-09-01T04:50:16.17576Z","end":"2026-09-01T04:50:16.393344Z","steps":["trace[675857010] 'agreement among raft nodes before linearized reading' (duration: 217.407225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:50:16.395226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.625129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-09-01T04:50:16.395324Z","caller":"traceutil/trace.go:171","msg":"trace[536264278] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16432; }","duration":"178.756912ms","start":"2026-09-01T04:50:16.216548Z","end":"2026-09-01T04:50:16.395305Z","steps":["trace[536264278] 'agreement among raft nodes before linearized reading' (duration: 178.585968ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:50:16.395701Z","caller":"traceutil/trace.go:171","msg":"trace[2019639321] transaction","detail":"{read_only:false; response_revision:16431; number_of_response:1; }","duration":"173.688363ms","start":"2026-09-01T04:50:16.221997Z","end":"2026-09-01T04:50:16.395686Z","steps":["trace[2019639321] 'process raft request' (duration: 173.023519ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:50:16.395867Z","caller":"traceutil/trace.go:171","msg":"trace[2051577984] transaction","detail":"{read_only:false; response_revision:16432; number_of_response:1; }","duration":"173.244273ms","start":"2026-09-01T04:50:16.222612Z","end":"2026-09-01T04:50:16.395856Z","steps":["trace[2051577984] 'process raft request' (duration: 172.477467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:50:16.396899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.700832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:50:16.396984Z","caller":"traceutil/trace.go:171","msg":"trace[620470821] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16432; }","duration":"173.831866ms","start":"2026-09-01T04:50:16.223133Z","end":"2026-09-01T04:50:16.396965Z","steps":["trace[620470821] 'agreement among raft nodes before linearized reading' (duration: 173.658262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:50:16.396972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.207536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5449"} {"level":"info","ts":"2026-09-01T04:50:16.397075Z","caller":"traceutil/trace.go:171","msg":"trace[1714477932] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16432; }","duration":"176.34123ms","start":"2026-09-01T04:50:16.220712Z","end":"2026-09-01T04:50:16.397053Z","steps":["trace[1714477932] 'agreement among raft nodes before linearized reading' (duration: 176.091274ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:51:38.507927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14529} {"level":"info","ts":"2026-09-01T04:51:38.64159Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14529,"took":"131.723809ms","hash":627073487,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":31375360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T04:51:38.641658Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":627073487,"revision":14529,"compact-revision":12526} {"level":"info","ts":"2026-09-01T04:55:51.8044Z","caller":"traceutil/trace.go:171","msg":"trace[1226369509] linearizableReadLoop","detail":"{readStateIndex:19558; appliedIndex:19557; }","duration":"148.99598ms","start":"2026-09-01T04:55:51.655378Z","end":"2026-09-01T04:55:51.804374Z","steps":["trace[1226369509] 'read index received' (duration: 93.441513ms)","trace[1226369509] 'applied index is now lower than readState.Index' (duration: 55.553547ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:55:51.804447Z","caller":"traceutil/trace.go:171","msg":"trace[593319261] transaction","detail":"{read_only:false; response_revision:18905; number_of_response:1; }","duration":"214.341808ms","start":"2026-09-01T04:55:51.590082Z","end":"2026-09-01T04:55:51.804424Z","steps":["trace[593319261] 'process raft request' (duration: 158.706259ms)","trace[593319261] 'compare' (duration: 55.464195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:55:51.804843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.668565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:55:51.805022Z","caller":"traceutil/trace.go:171","msg":"trace[2052293059] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:18905; }","duration":"130.897199ms","start":"2026-09-01T04:55:51.674103Z","end":"2026-09-01T04:55:51.805Z","steps":["trace[2052293059] 'agreement among raft nodes before linearized reading' (duration: 130.672684ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:51.804857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.486531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-01T04:55:51.805274Z","caller":"traceutil/trace.go:171","msg":"trace[390185707] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18905; }","duration":"149.94214ms","start":"2026-09-01T04:55:51.655295Z","end":"2026-09-01T04:55:51.805237Z","steps":["trace[390185707] 'agreement among raft nodes before linearized reading' (duration: 149.199964ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:52.093842Z","caller":"traceutil/trace.go:171","msg":"trace[395009393] linearizableReadLoop","detail":"{readStateIndex:19566; appliedIndex:19565; }","duration":"172.64909ms","start":"2026-09-01T04:55:51.921169Z","end":"2026-09-01T04:55:52.093818Z","steps":["trace[395009393] 'read index received' (duration: 172.463096ms)","trace[395009393] 'applied index is now lower than readState.Index' (duration: 184.634µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T04:55:52.094061Z","caller":"traceutil/trace.go:171","msg":"trace[2103473329] transaction","detail":"{read_only:false; response_revision:18913; number_of_response:1; }","duration":"175.607234ms","start":"2026-09-01T04:55:51.918412Z","end":"2026-09-01T04:55:52.09402Z","steps":["trace[2103473329] 'process raft request' (duration: 175.215715ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.094168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.060568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T04:55:52.094097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.897576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.93\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-09-01T04:55:52.094287Z","caller":"traceutil/trace.go:171","msg":"trace[962121249] range","detail":"{range_begin:/registry/masterleases/162.253.55.93; range_end:; response_count:1; response_revision:18913; }","duration":"173.13023ms","start":"2026-09-01T04:55:51.921145Z","end":"2026-09-01T04:55:52.094275Z","steps":["trace[962121249] 'agreement among raft nodes before linearized reading' (duration: 172.803783ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:52.094249Z","caller":"traceutil/trace.go:171","msg":"trace[647657322] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18913; }","duration":"140.1711ms","start":"2026-09-01T04:55:51.95406Z","end":"2026-09-01T04:55:52.094231Z","steps":["trace[647657322] 'agreement among raft nodes before linearized reading' (duration: 140.077408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.345564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.510108ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-01T04:55:52.345762Z","caller":"traceutil/trace.go:171","msg":"trace[588896818] linearizableReadLoop","detail":"{readStateIndex:19567; appliedIndex:19566; }","duration":"246.876349ms","start":"2026-09-01T04:55:52.098861Z","end":"2026-09-01T04:55:52.345737Z","steps":["trace[588896818] 'read index received' (duration: 60.078194ms)","trace[588896818] 'applied index is now lower than readState.Index' (duration: 186.795915ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:55:52.345965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.077004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-01T04:55:52.346078Z","caller":"traceutil/trace.go:171","msg":"trace[1136581059] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18913; }","duration":"247.236168ms","start":"2026-09-01T04:55:52.098815Z","end":"2026-09-01T04:55:52.346051Z","steps":["trace[1136581059] 'agreement among raft nodes before linearized reading' (duration: 247.015962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.346133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.296106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"warn","ts":"2026-09-01T04:55:52.346136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.716207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-01T04:55:52.346185Z","caller":"traceutil/trace.go:171","msg":"trace[1049460236] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18913; }","duration":"226.361997ms","start":"2026-09-01T04:55:52.119806Z","end":"2026-09-01T04:55:52.346168Z","steps":["trace[1049460236] 'agreement among raft nodes before linearized reading' (duration: 226.0901ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:52.346237Z","caller":"traceutil/trace.go:171","msg":"trace[685582573] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18913; }","duration":"195.83138ms","start":"2026-09-01T04:55:52.150361Z","end":"2026-09-01T04:55:52.346192Z","steps":["trace[685582573] 'agreement among raft nodes before linearized reading' (duration: 195.549973ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:52.650304Z","caller":"traceutil/trace.go:171","msg":"trace[552655786] linearizableReadLoop","detail":"{readStateIndex:19569; appliedIndex:19568; }","duration":"205.205381ms","start":"2026-09-01T04:55:52.44505Z","end":"2026-09-01T04:55:52.650256Z","steps":["trace[552655786] 'read index received' (duration: 205.12979ms)","trace[552655786] 'applied index is now lower than readState.Index' (duration: 74.241µs)"],"step_count":2} {"level":"warn","ts":"2026-09-01T04:55:52.650816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.003629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T04:55:52.651045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.635479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-01T04:55:52.651107Z","caller":"traceutil/trace.go:171","msg":"trace[1438133278] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18915; }","duration":"301.73759ms","start":"2026-09-01T04:55:52.349355Z","end":"2026-09-01T04:55:52.651093Z","steps":["trace[1438133278] 'agreement among raft nodes before linearized reading' (duration: 301.551996ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.651154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:52.349336Z","time spent":"301.806362ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-01T04:55:52.650896Z","caller":"traceutil/trace.go:171","msg":"trace[951254708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18915; }","duration":"258.129812ms","start":"2026-09-01T04:55:52.392749Z","end":"2026-09-01T04:55:52.650879Z","steps":["trace[951254708] 'agreement among raft nodes before linearized reading' (duration: 257.669382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.65153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.572544ms","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:55:52.650338Z","caller":"traceutil/trace.go:171","msg":"trace[211017672] transaction","detail":"{read_only:false; response_revision:18915; number_of_response:1; }","duration":"300.778181ms","start":"2026-09-01T04:55:52.349534Z","end":"2026-09-01T04:55:52.650312Z","steps":["trace[211017672] 'process raft request' (duration: 300.525445ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:55:52.651605Z","caller":"traceutil/trace.go:171","msg":"trace[1812481790] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18915; }","duration":"298.629975ms","start":"2026-09-01T04:55:52.352939Z","end":"2026-09-01T04:55:52.651569Z","steps":["trace[1812481790] 'agreement among raft nodes before linearized reading' (duration: 298.496882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.652135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T04:55:52.349504Z","time spent":"302.086539ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T04:55:52.666222Z","caller":"traceutil/trace.go:171","msg":"trace[31784058] transaction","detail":"{read_only:false; response_revision:18916; number_of_response:1; }","duration":"216.826003ms","start":"2026-09-01T04:55:52.449353Z","end":"2026-09-01T04:55:52.666179Z","steps":["trace[31784058] 'process raft request' (duration: 216.537697ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.666622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.443744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-x7w6g\" ","response":"range_response_count:1 size:22011"} {"level":"info","ts":"2026-09-01T04:55:52.666697Z","caller":"traceutil/trace.go:171","msg":"trace[1781036425] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-x7w6g; range_end:; response_count:1; response_revision:18916; }","duration":"184.72573ms","start":"2026-09-01T04:55:52.481954Z","end":"2026-09-01T04:55:52.666679Z","steps":["trace[1781036425] 'agreement among raft nodes before linearized reading' (duration: 184.196969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T04:55:52.667507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.797875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-01T04:55:52.667585Z","caller":"traceutil/trace.go:171","msg":"trace[545434243] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:18916; }","duration":"219.926428ms","start":"2026-09-01T04:55:52.447637Z","end":"2026-09-01T04:55:52.667563Z","steps":["trace[545434243] 'agreement among raft nodes before linearized reading' (duration: 218.519138ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T04:56:34.098754Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T04:56:34.104494Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-01T04:56:34.104573Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-01T04:56:38.516565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16964} {"level":"info","ts":"2026-09-01T04:56:38.615885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16964,"took":"96.230497ms","hash":454663029,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":33521664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-01T04:56:38.615939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":454663029,"revision":16964,"compact-revision":14529} {"level":"info","ts":"2026-09-01T05:01:38.529737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19511} {"level":"info","ts":"2026-09-01T05:01:38.659641Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19511,"took":"126.807703ms","hash":913665854,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-01T05:01:38.659732Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":913665854,"revision":19511,"compact-revision":16964} {"level":"info","ts":"2026-09-01T05:01:48.674051Z","caller":"traceutil/trace.go:171","msg":"trace[802337049] transaction","detail":"{read_only:false; response_revision:21729; number_of_response:1; }","duration":"234.989217ms","start":"2026-09-01T05:01:48.439042Z","end":"2026-09-01T05:01:48.674031Z","steps":["trace[802337049] 'process raft request' (duration: 234.835573ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:01:48.831355Z","caller":"traceutil/trace.go:171","msg":"trace[1063389065] linearizableReadLoop","detail":"{readStateIndex:22476; appliedIndex:22474; }","duration":"294.765761ms","start":"2026-09-01T05:01:48.536567Z","end":"2026-09-01T05:01:48.831333Z","steps":["trace[1063389065] 'read index received' (duration: 137.398621ms)","trace[1063389065] 'applied index is now lower than readState.Index' (duration: 157.36634ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:01:48.831489Z","caller":"traceutil/trace.go:171","msg":"trace[412305212] transaction","detail":"{read_only:false; response_revision:21731; number_of_response:1; }","duration":"273.30738ms","start":"2026-09-01T05:01:48.558171Z","end":"2026-09-01T05:01:48.831478Z","steps":["trace[412305212] 'process raft request' (duration: 273.119655ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:01:48.831493Z","caller":"traceutil/trace.go:171","msg":"trace[1169350227] transaction","detail":"{read_only:false; response_revision:21730; number_of_response:1; }","duration":"323.069319ms","start":"2026-09-01T05:01:48.508343Z","end":"2026-09-01T05:01:48.831412Z","steps":["trace[1169350227] 'process raft request' (duration: 290.03221ms)","trace[1169350227] 'compare' (duration: 32.803204ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:01:48.83166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:01:48.508319Z","time spent":"323.259723ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:01:48.831682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.155944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-01T05:01:48.831749Z","caller":"traceutil/trace.go:171","msg":"trace[2014960341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21731; }","duration":"190.269266ms","start":"2026-09-01T05:01:48.641462Z","end":"2026-09-01T05:01:48.831731Z","steps":["trace[2014960341] 'agreement among raft nodes before linearized reading' (duration: 190.061012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:01:48.831747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.708596ms","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-01T05:01:48.8318Z","caller":"traceutil/trace.go:171","msg":"trace[53909624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21731; }","duration":"170.765817ms","start":"2026-09-01T05:01:48.661018Z","end":"2026-09-01T05:01:48.831784Z","steps":["trace[53909624] 'agreement among raft nodes before linearized reading' (duration: 170.598164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:01:48.83179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.213531ms","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:01:48.83193Z","caller":"traceutil/trace.go:171","msg":"trace[476808231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21731; }","duration":"295.392165ms","start":"2026-09-01T05:01:48.536512Z","end":"2026-09-01T05:01:48.831904Z","steps":["trace[476808231] 'agreement among raft nodes before linearized reading' (duration: 295.114269ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:01:49.075284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.755586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:01:49.075456Z","caller":"traceutil/trace.go:171","msg":"trace[1532905758] transaction","detail":"{read_only:false; response_revision:21732; number_of_response:1; }","duration":"239.817012ms","start":"2026-09-01T05:01:48.835616Z","end":"2026-09-01T05:01:49.075433Z","steps":["trace[1532905758] 'process raft request' (duration: 102.805429ms)","trace[1532905758] 'compare' (duration: 136.554092ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:01:49.37769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.2422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-01T05:01:49.377952Z","caller":"traceutil/trace.go:171","msg":"trace[992121078] transaction","detail":"{read_only:false; response_revision:21733; number_of_response:1; }","duration":"542.271367ms","start":"2026-09-01T05:01:48.835649Z","end":"2026-09-01T05:01:49.377921Z","steps":["trace[992121078] 'process raft request' (duration: 361.717759ms)","trace[992121078] 'compare' (duration: 180.003156ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:01:49.378012Z","caller":"traceutil/trace.go:171","msg":"trace[214870560] linearizableReadLoop","detail":"{readStateIndex:22482; appliedIndex:22478; }","duration":"295.098579ms","start":"2026-09-01T05:01:49.082892Z","end":"2026-09-01T05:01:49.377991Z","steps":["trace[214870560] 'read index received' (duration: 114.52935ms)","trace[214870560] 'applied index is now lower than readState.Index' (duration: 180.567769ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:01:49.378095Z","caller":"traceutil/trace.go:171","msg":"trace[1849253195] transaction","detail":"{read_only:false; response_revision:21736; number_of_response:1; }","duration":"541.704885ms","start":"2026-09-01T05:01:48.836377Z","end":"2026-09-01T05:01:49.378082Z","steps":["trace[1849253195] 'process raft request' (duration: 541.567422ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:01:49.378069Z","caller":"traceutil/trace.go:171","msg":"trace[1522947749] transaction","detail":"{read_only:false; response_revision:21734; number_of_response:1; }","duration":"542.175485ms","start":"2026-09-01T05:01:48.835857Z","end":"2026-09-01T05:01:49.378032Z","steps":["trace[1522947749] 'process raft request' (duration: 541.949831ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:01:49.378106Z","caller":"traceutil/trace.go:171","msg":"trace[2141892292] transaction","detail":"{read_only:false; response_revision:21735; number_of_response:1; }","duration":"542.127944ms","start":"2026-09-01T05:01:48.835908Z","end":"2026-09-01T05:01:49.378036Z","steps":["trace[2141892292] 'process raft request' (duration: 541.987081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:01:49.378119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:01:48.835632Z","time spent":"542.40226ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:01:49.378432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:01:48.835884Z","time spent":"542.453581ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:01:49.378594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.701231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-01T05:01:49.378437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:01:48.836167Z","time spent":"541.961171ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T05:01:49.378693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T05:01:48.835814Z","time spent":"542.514282ms","remote":"127.0.0.1:47682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-01T05:01:49.378688Z","caller":"traceutil/trace.go:171","msg":"trace[640545003] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:21736; }","duration":"295.767732ms","start":"2026-09-01T05:01:49.082876Z","end":"2026-09-01T05:01:49.378644Z","steps":["trace[640545003] 'agreement among raft nodes before linearized reading' (duration: 295.66714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:01:49.395383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.035475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-01T05:01:49.39542Z","caller":"traceutil/trace.go:171","msg":"trace[2099125674] transaction","detail":"{read_only:false; response_revision:21738; number_of_response:1; }","duration":"100.931787ms","start":"2026-09-01T05:01:49.294453Z","end":"2026-09-01T05:01:49.395385Z","steps":["trace[2099125674] 'process raft request' (duration: 100.60314ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:01:49.395498Z","caller":"traceutil/trace.go:171","msg":"trace[1489343194] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:21738; }","duration":"280.184658ms","start":"2026-09-01T05:01:49.115288Z","end":"2026-09-01T05:01:49.395473Z","steps":["trace[1489343194] 'agreement among raft nodes before linearized reading' (duration: 279.965493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:01:49.395922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.126127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:27 size:241590"} {"level":"info","ts":"2026-09-01T05:01:49.396003Z","caller":"traceutil/trace.go:171","msg":"trace[807544547] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:27; response_revision:21738; }","duration":"233.324381ms","start":"2026-09-01T05:01:49.162657Z","end":"2026-09-01T05:01:49.395982Z","steps":["trace[807544547] 'agreement among raft nodes before linearized reading' (duration: 232.760029ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:01:49.680458Z","caller":"traceutil/trace.go:171","msg":"trace[728210902] transaction","detail":"{read_only:false; response_revision:21739; number_of_response:1; }","duration":"159.354163ms","start":"2026-09-01T05:01:49.521082Z","end":"2026-09-01T05:01:49.680436Z","steps":["trace[728210902] 'process raft request' (duration: 73.712593ms)","trace[728210902] 'compare' (duration: 85.471155ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:05:59.031111Z","caller":"traceutil/trace.go:171","msg":"trace[1718246311] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"268.061373ms","start":"2026-09-01T05:05:58.763006Z","end":"2026-09-01T05:05:59.031067Z","steps":["trace[1718246311] 'process raft request' (duration: 194.04824ms)","trace[1718246311] 'compare' (duration: 73.198716ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T05:05:59.031328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.883676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-01T05:05:59.031106Z","caller":"traceutil/trace.go:171","msg":"trace[1663893451] linearizableReadLoop","detail":"{readStateIndex:25144; appliedIndex:25143; }","duration":"243.640681ms","start":"2026-09-01T05:05:58.787405Z","end":"2026-09-01T05:05:59.031045Z","steps":["trace[1663893451] 'read index received' (duration: 169.586986ms)","trace[1663893451] 'applied index is now lower than readState.Index' (duration: 74.046945ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T05:05:59.031414Z","caller":"traceutil/trace.go:171","msg":"trace[1842476834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24323; }","duration":"244.035919ms","start":"2026-09-01T05:05:58.787361Z","end":"2026-09-01T05:05:59.031397Z","steps":["trace[1842476834] 'agreement among raft nodes before linearized reading' (duration: 243.775034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:05:59.031624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.542715ms","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":"warn","ts":"2026-09-01T05:05:59.031629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.651482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-01T05:05:59.031651Z","caller":"traceutil/trace.go:171","msg":"trace[710624851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24323; }","duration":"233.595035ms","start":"2026-09-01T05:05:58.798048Z","end":"2026-09-01T05:05:59.031643Z","steps":["trace[710624851] 'agreement among raft nodes before linearized reading' (duration: 233.522814ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:05:59.031741Z","caller":"traceutil/trace.go:171","msg":"trace[1029501792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24323; }","duration":"243.771284ms","start":"2026-09-01T05:05:58.787904Z","end":"2026-09-01T05:05:59.031675Z","steps":["trace[1029501792] 'agreement among raft nodes before linearized reading' (duration: 243.56468ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T05:05:59.031852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.283218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418321"} {"level":"info","ts":"2026-09-01T05:05:59.031956Z","caller":"traceutil/trace.go:171","msg":"trace[1919241614] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24323; }","duration":"211.446572ms","start":"2026-09-01T05:05:58.820472Z","end":"2026-09-01T05:05:59.031919Z","steps":["trace[1919241614] 'agreement among raft nodes before linearized reading' (duration: 211.075604ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T05:06:38.536866Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21677} {"level":"info","ts":"2026-09-01T05:06:38.653036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21677,"took":"112.141128ms","hash":3381813460,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":42848256,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-01T05:06:38.653124Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3381813460,"revision":21677,"compact-revision":19511} {"level":"info","ts":"2026-09-01T05:10:11.752005Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006ae7.wal"} {"level":"info","ts":"2026-09-01T05:11:38.544844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24749} {"level":"info","ts":"2026-09-01T05:11:38.700766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24749,"took":"151.865377ms","hash":3632835260,"current-db-size-bytes":57425920,"current-db-size":"57 MB","current-db-size-in-use-bytes":32428032,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-01T05:11:38.70084Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3632835260,"revision":24749,"compact-revision":21677} {"level":"info","ts":"2026-09-01T05:15:58.041168Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ea06c5748459930b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-01T05:15:58.046889Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-01T05:15:58.046997Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}