{"level":"warn","ts":"2026-09-04T03:15:26.727722Z","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-04T03:15:26.727868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.79: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.79:2380","--initial-cluster=instance=https://162.253.55.79:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.79:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.79: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-04T03:15:26.72797Z","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-04T03:15:26.727994Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-09-04T03:15:26.728031Z","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-04T03:15:26.729165Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"]} {"level":"info","ts":"2026-09-04T03:15:26.729712Z","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.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.79: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-04T03:15:26.734476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.389529ms"} {"level":"info","ts":"2026-09-04T03:15:26.750497Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"401ca643156fc5da","cluster-id":"e9f568c3681bb0d0"} {"level":"info","ts":"2026-09-04T03:15:26.750657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=()"} {"level":"info","ts":"2026-09-04T03:15:26.75069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 0"} {"level":"info","ts":"2026-09-04T03:15:26.750701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 401ca643156fc5da [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-04T03:15:26.750714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became follower at term 1"} {"level":"info","ts":"2026-09-04T03:15:26.750764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"warn","ts":"2026-09-04T03:15:26.758115Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-04T03:15:26.76101Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-04T03:15:26.76271Z","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-04T03:15:26.766248Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"401ca643156fc5da","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-04T03:15:26.766807Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"401ca643156fc5da","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-04T03:15:26.766839Z","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-04T03:15:26.767017Z","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-04T03:15:26.767047Z","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-04T03:15:26.767958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da switched to configuration voters=(4619750124827952602)"} {"level":"info","ts":"2026-09-04T03:15:26.768295Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","added-peer-id":"401ca643156fc5da","added-peer-peer-urls":["https://162.253.55.79:2380"]} {"level":"info","ts":"2026-09-04T03:15:26.770577Z","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-04T03:15:26.770833Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-09-04T03:15:26.770951Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"401ca643156fc5da","initial-advertise-peer-urls":["https://162.253.55.79:2380"],"listen-peer-urls":["https://162.253.55.79:2380"],"advertise-client-urls":["https://162.253.55.79:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.79:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-04T03:15:26.770969Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.79:2380"} {"level":"info","ts":"2026-09-04T03:15:26.771003Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-04T03:15:26.951206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da is starting a new election at term 1"} {"level":"info","ts":"2026-09-04T03:15:26.951302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became pre-candidate at term 1"} {"level":"info","ts":"2026-09-04T03:15:26.951389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgPreVoteResp from 401ca643156fc5da at term 1"} {"level":"info","ts":"2026-09-04T03:15:26.951416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became candidate at term 2"} {"level":"info","ts":"2026-09-04T03:15:26.951435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da received MsgVoteResp from 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-09-04T03:15:26.951454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"401ca643156fc5da became leader at term 2"} {"level":"info","ts":"2026-09-04T03:15:26.951476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 401ca643156fc5da elected leader 401ca643156fc5da at term 2"} {"level":"info","ts":"2026-09-04T03:15:26.952637Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:26.953562Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"401ca643156fc5da","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.79:2379]}","request-path":"/0/members/401ca643156fc5da/attributes","cluster-id":"e9f568c3681bb0d0","publish-timeout":"7s"} {"level":"info","ts":"2026-09-04T03:15:26.953574Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T03:15:26.953607Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T03:15:26.953973Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-04T03:15:26.954004Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-04T03:15:26.954165Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e9f568c3681bb0d0","local-member-id":"401ca643156fc5da","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:26.954419Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:26.954489Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:26.956654Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.79:2379"} {"level":"info","ts":"2026-09-04T03:15:26.957064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-04T03:15:30.16919Z","caller":"traceutil/trace.go:171","msg":"trace[1521467244] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"111.483892ms","start":"2026-09-04T03:15:30.057664Z","end":"2026-09-04T03:15:30.169148Z","steps":["trace[1521467244] 'process raft request' (duration: 98.411925ms)","trace[1521467244] 'compare' (duration: 12.897352ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:15:45.204282Z","caller":"traceutil/trace.go:171","msg":"trace[494376313] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"165.779814ms","start":"2026-09-04T03:15:45.038472Z","end":"2026-09-04T03:15:45.204252Z","steps":["trace[494376313] 'process raft request' (duration: 165.451544ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:15:50.596326Z","caller":"traceutil/trace.go:171","msg":"trace[627141715] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"110.014109ms","start":"2026-09-04T03:15:50.486268Z","end":"2026-09-04T03:15:50.596282Z","steps":["trace[627141715] 'process raft request' (duration: 109.72658ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:14.21415Z","caller":"traceutil/trace.go:171","msg":"trace[1414478012] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"275.206418ms","start":"2026-09-04T03:16:13.938903Z","end":"2026-09-04T03:16:14.214109Z","steps":["trace[1414478012] 'process raft request' (duration: 274.241909ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:14.861759Z","caller":"traceutil/trace.go:171","msg":"trace[1292801187] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"189.339617ms","start":"2026-09-04T03:16:14.672381Z","end":"2026-09-04T03:16:14.86172Z","steps":["trace[1292801187] 'read index received' (duration: 189.168382ms)","trace[1292801187] 'applied index is now lower than readState.Index' (duration: 169.765µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:16:14.861826Z","caller":"traceutil/trace.go:171","msg":"trace[1865813733] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"571.178584ms","start":"2026-09-04T03:16:14.290599Z","end":"2026-09-04T03:16:14.861778Z","steps":["trace[1865813733] 'process raft request' (duration: 570.940367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:14.862073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.679228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:16:14.862222Z","caller":"traceutil/trace.go:171","msg":"trace[1259509019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"189.923134ms","start":"2026-09-04T03:16:14.672273Z","end":"2026-09-04T03:16:14.862196Z","steps":["trace[1259509019] 'agreement among raft nodes before linearized reading' (duration: 189.653416ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:14.862283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.337491ms","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-04T03:16:14.862408Z","caller":"traceutil/trace.go:171","msg":"trace[1944165509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"104.541807ms","start":"2026-09-04T03:16:14.757839Z","end":"2026-09-04T03:16:14.862381Z","steps":["trace[1944165509] 'agreement among raft nodes before linearized reading' (duration: 104.126635ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:14.862694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:16:14.290562Z","time spent":"571.365279ms","remote":"127.0.0.1:60736","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-04T03:16:31.602147Z","caller":"traceutil/trace.go:171","msg":"trace[1544949067] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"110.794772ms","start":"2026-09-04T03:16:31.491222Z","end":"2026-09-04T03:16:31.602017Z","steps":["trace[1544949067] 'process raft request' (duration: 110.588696ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:31.785258Z","caller":"traceutil/trace.go:171","msg":"trace[421854839] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"227.259704ms","start":"2026-09-04T03:16:31.557967Z","end":"2026-09-04T03:16:31.785227Z","steps":["trace[421854839] 'read index received' (duration: 43.823883ms)","trace[421854839] 'applied index is now lower than readState.Index' (duration: 183.434521ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:16:31.785401Z","caller":"traceutil/trace.go:171","msg":"trace[1814761276] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"292.05408ms","start":"2026-09-04T03:16:31.493297Z","end":"2026-09-04T03:16:31.785351Z","steps":["trace[1814761276] 'process raft request' (duration: 247.173786ms)","trace[1814761276] 'compare' (duration: 44.630755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:16:31.785539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.544453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:16:31.785583Z","caller":"traceutil/trace.go:171","msg":"trace[396035001] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:806; }","duration":"227.640216ms","start":"2026-09-04T03:16:31.557929Z","end":"2026-09-04T03:16:31.78557Z","steps":["trace[396035001] 'agreement among raft nodes before linearized reading' (duration: 227.466221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:31.785616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.520575ms","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-04T03:16:31.78571Z","caller":"traceutil/trace.go:171","msg":"trace[139149829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"216.653939ms","start":"2026-09-04T03:16:31.569031Z","end":"2026-09-04T03:16:31.785685Z","steps":["trace[139149829] 'agreement among raft nodes before linearized reading' (duration: 216.386531ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:02.84938Z","caller":"traceutil/trace.go:171","msg":"trace[1708585132] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"197.870905ms","start":"2026-09-04T03:17:02.65147Z","end":"2026-09-04T03:17:02.849341Z","steps":["trace[1708585132] 'process raft request' (duration: 197.641648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:51.98839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.423598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:17:51.988529Z","caller":"traceutil/trace.go:171","msg":"trace[2074876645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"315.631004ms","start":"2026-09-04T03:17:51.672867Z","end":"2026-09-04T03:17:51.988498Z","steps":["trace[2074876645] 'range keys from in-memory index tree' (duration: 315.240681ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:51.988589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:17:51.67283Z","time spent":"315.742037ms","remote":"127.0.0.1:60396","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-04T03:17:52.507382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.223381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63e2067c-0b1a-4255-93f7-de7e28b3fbf2\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-04T03:17:52.507468Z","caller":"traceutil/trace.go:171","msg":"trace[214060909] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63e2067c-0b1a-4255-93f7-de7e28b3fbf2; range_end:; response_count:1; response_revision:1895; }","duration":"307.359844ms","start":"2026-09-04T03:17:52.200086Z","end":"2026-09-04T03:17:52.507446Z","steps":["trace[214060909] 'range keys from in-memory index tree' (duration: 306.920931ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:52.507448Z","caller":"traceutil/trace.go:171","msg":"trace[943004647] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"282.722134ms","start":"2026-09-04T03:17:52.224694Z","end":"2026-09-04T03:17:52.507416Z","steps":["trace[943004647] 'read index received' (duration: 229.31895ms)","trace[943004647] 'applied index is now lower than readState.Index' (duration: 53.401874ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:17:52.507517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:17:52.200056Z","time spent":"307.451197ms","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63e2067c-0b1a-4255-93f7-de7e28b3fbf2\" "} {"level":"info","ts":"2026-09-04T03:17:52.507495Z","caller":"traceutil/trace.go:171","msg":"trace[1827027703] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"304.939872ms","start":"2026-09-04T03:17:52.202518Z","end":"2026-09-04T03:17:52.507458Z","steps":["trace[1827027703] 'process raft request' (duration: 251.353862ms)","trace[1827027703] 'compare' (duration: 53.416525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:17:52.507708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.003443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-04T03:17:52.507767Z","caller":"traceutil/trace.go:171","msg":"trace[1025443894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1896; }","duration":"283.096296ms","start":"2026-09-04T03:17:52.224647Z","end":"2026-09-04T03:17:52.507744Z","steps":["trace[1025443894] 'agreement among raft nodes before linearized reading' (duration: 282.90052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:52.507921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:17:52.202488Z","time spent":"305.079066ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:17:52.551054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.253948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-04T03:17:52.551073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.136436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-04T03:17:52.551124Z","caller":"traceutil/trace.go:171","msg":"trace[832664987] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1897; }","duration":"275.230109ms","start":"2026-09-04T03:17:52.275879Z","end":"2026-09-04T03:17:52.551109Z","steps":["trace[832664987] 'agreement among raft nodes before linearized reading' (duration: 275.076685ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:52.551126Z","caller":"traceutil/trace.go:171","msg":"trace[977382239] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1897; }","duration":"231.372121ms","start":"2026-09-04T03:17:52.31973Z","end":"2026-09-04T03:17:52.551102Z","steps":["trace[977382239] 'agreement among raft nodes before linearized reading' (duration: 231.169035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:52.551221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.413067ms","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-04T03:17:52.551315Z","caller":"traceutil/trace.go:171","msg":"trace[2053943444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1897; }","duration":"322.578032ms","start":"2026-09-04T03:17:52.228717Z","end":"2026-09-04T03:17:52.551295Z","steps":["trace[2053943444] 'agreement among raft nodes before linearized reading' (duration: 322.16283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:52.551379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:17:52.228688Z","time spent":"322.676106ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-04T03:17:52.551175Z","caller":"traceutil/trace.go:171","msg":"trace[1434654360] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"140.262484ms","start":"2026-09-04T03:17:52.410821Z","end":"2026-09-04T03:17:52.551083Z","steps":["trace[1434654360] 'process raft request' (duration: 139.7694ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:06.480499Z","caller":"traceutil/trace.go:171","msg":"trace[1913235465] transaction","detail":"{read_only:false; response_revision:2024; number_of_response:1; }","duration":"272.372734ms","start":"2026-09-04T03:18:06.208103Z","end":"2026-09-04T03:18:06.480476Z","steps":["trace[1913235465] 'process raft request' (duration: 271.878989ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:37.711078Z","caller":"traceutil/trace.go:171","msg":"trace[134676507] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"197.795262ms","start":"2026-09-04T03:18:37.513258Z","end":"2026-09-04T03:18:37.711053Z","steps":["trace[134676507] 'process raft request' (duration: 197.646277ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:37.775511Z","caller":"traceutil/trace.go:171","msg":"trace[646819454] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2287; }","duration":"148.80747ms","start":"2026-09-04T03:18:37.626664Z","end":"2026-09-04T03:18:37.775471Z","steps":["trace[646819454] 'read index received' (duration: 84.255767ms)","trace[646819454] 'applied index is now lower than readState.Index' (duration: 64.550253ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:18:37.775736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.042298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:18:37.775833Z","caller":"traceutil/trace.go:171","msg":"trace[706015771] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2201; }","duration":"149.157041ms","start":"2026-09-04T03:18:37.626657Z","end":"2026-09-04T03:18:37.775814Z","steps":["trace[706015771] 'agreement among raft nodes before linearized reading' (duration: 148.975536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:37.775795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.943681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:18:37.77592Z","caller":"traceutil/trace.go:171","msg":"trace[373767513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2201; }","duration":"104.108545ms","start":"2026-09-04T03:18:37.671796Z","end":"2026-09-04T03:18:37.775904Z","steps":["trace[373767513] 'agreement among raft nodes before linearized reading' (duration: 103.92875ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:37.775766Z","caller":"traceutil/trace.go:171","msg":"trace[432678974] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"170.634967ms","start":"2026-09-04T03:18:37.604911Z","end":"2026-09-04T03:18:37.775546Z","steps":["trace[432678974] 'process raft request' (duration: 170.371539ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:43.909741Z","caller":"traceutil/trace.go:171","msg":"trace[976654041] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"178.940073ms","start":"2026-09-04T03:18:43.730777Z","end":"2026-09-04T03:18:43.909717Z","steps":["trace[976654041] 'process raft request' (duration: 178.748038ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:43.965053Z","caller":"traceutil/trace.go:171","msg":"trace[1275807565] linearizableReadLoop","detail":"{readStateIndex:2323; appliedIndex:2321; }","duration":"151.645905ms","start":"2026-09-04T03:18:43.813378Z","end":"2026-09-04T03:18:43.965024Z","steps":["trace[1275807565] 'read index received' (duration: 96.244503ms)","trace[1275807565] 'applied index is now lower than readState.Index' (duration: 55.400262ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:18:43.965187Z","caller":"traceutil/trace.go:171","msg":"trace[970090236] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"170.352339ms","start":"2026-09-04T03:18:43.79481Z","end":"2026-09-04T03:18:43.965162Z","steps":["trace[970090236] 'process raft request' (duration: 170.03333ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:43.965375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.977565ms","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-04T03:18:43.965426Z","caller":"traceutil/trace.go:171","msg":"trace[674201810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2234; }","duration":"152.094308ms","start":"2026-09-04T03:18:43.813321Z","end":"2026-09-04T03:18:43.965415Z","steps":["trace[674201810] 'agreement among raft nodes before linearized reading' (duration: 151.889792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:44.495446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.746864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:18:44.495539Z","caller":"traceutil/trace.go:171","msg":"trace[1307399204] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2235; }","duration":"355.861368ms","start":"2026-09-04T03:18:44.139658Z","end":"2026-09-04T03:18:44.495519Z","steps":["trace[1307399204] 'count revisions from in-memory index tree' (duration: 355.63856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:44.495584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:18:44.139591Z","time spent":"355.981282ms","remote":"127.0.0.1:38994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-09-04T03:18:44.495639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.275334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:18:44.495778Z","caller":"traceutil/trace.go:171","msg":"trace[735907845] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2235; }","duration":"288.46859ms","start":"2026-09-04T03:18:44.207285Z","end":"2026-09-04T03:18:44.495754Z","steps":["trace[735907845] 'count revisions from in-memory index tree' (duration: 288.213913ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:45.001849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.185334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T03:18:45.001999Z","caller":"traceutil/trace.go:171","msg":"trace[274657750] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"281.051759ms","start":"2026-09-04T03:18:44.720894Z","end":"2026-09-04T03:18:45.001946Z","steps":["trace[274657750] 'process raft request' (duration: 137.512525ms)","trace[274657750] 'compare' (duration: 143.0646ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:18:45.023198Z","caller":"traceutil/trace.go:171","msg":"trace[1286820592] linearizableReadLoop","detail":"{readStateIndex:2327; appliedIndex:2325; }","duration":"222.971488ms","start":"2026-09-04T03:18:44.800201Z","end":"2026-09-04T03:18:45.023172Z","steps":["trace[1286820592] 'read index received' (duration: 58.214386ms)","trace[1286820592] 'applied index is now lower than readState.Index' (duration: 164.756032ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:18:45.023254Z","caller":"traceutil/trace.go:171","msg":"trace[649232601] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"287.654445ms","start":"2026-09-04T03:18:44.73557Z","end":"2026-09-04T03:18:45.023224Z","steps":["trace[649232601] 'process raft request' (duration: 287.447609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:45.023497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.593988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:18:45.023558Z","caller":"traceutil/trace.go:171","msg":"trace[1298500172] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2238; }","duration":"112.688741ms","start":"2026-09-04T03:18:44.910856Z","end":"2026-09-04T03:18:45.023545Z","steps":["trace[1298500172] 'agreement among raft nodes before linearized reading' (duration: 112.575147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:45.023623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.57316ms","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-04T03:18:45.023717Z","caller":"traceutil/trace.go:171","msg":"trace[1559036936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2238; }","duration":"216.657492ms","start":"2026-09-04T03:18:44.807012Z","end":"2026-09-04T03:18:45.023669Z","steps":["trace[1559036936] 'agreement among raft nodes before linearized reading' (duration: 216.333953ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:45.023661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.429392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-04T03:18:45.02388Z","caller":"traceutil/trace.go:171","msg":"trace[53945372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2238; }","duration":"223.666259ms","start":"2026-09-04T03:18:44.800172Z","end":"2026-09-04T03:18:45.023838Z","steps":["trace[53945372] 'agreement among raft nodes before linearized reading' (duration: 223.146014ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:19:38.524236Z","caller":"traceutil/trace.go:171","msg":"trace[94616004] linearizableReadLoop","detail":"{readStateIndex:2806; appliedIndex:2805; }","duration":"157.887121ms","start":"2026-09-04T03:19:38.36632Z","end":"2026-09-04T03:19:38.524207Z","steps":["trace[94616004] 'read index received' (duration: 137.580709ms)","trace[94616004] 'applied index is now lower than readState.Index' (duration: 20.305032ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:19:38.524304Z","caller":"traceutil/trace.go:171","msg":"trace[2088428863] transaction","detail":"{read_only:false; response_revision:2697; number_of_response:1; }","duration":"372.713756ms","start":"2026-09-04T03:19:38.151556Z","end":"2026-09-04T03:19:38.52427Z","steps":["trace[2088428863] 'process raft request' (duration: 352.343092ms)","trace[2088428863] 'compare' (duration: 20.072795ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:19:38.524451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:19:38.151532Z","time spent":"372.83962ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:19:38.524457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.112298ms","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-04T03:19:38.524494Z","caller":"traceutil/trace.go:171","msg":"trace[956609711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2697; }","duration":"158.218301ms","start":"2026-09-04T03:19:38.366263Z","end":"2026-09-04T03:19:38.524481Z","steps":["trace[956609711] 'agreement among raft nodes before linearized reading' (duration: 158.044975ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:24:01.248347Z","caller":"traceutil/trace.go:171","msg":"trace[995109373] linearizableReadLoop","detail":"{readStateIndex:4540; appliedIndex:4539; }","duration":"120.449748ms","start":"2026-09-04T03:24:01.127869Z","end":"2026-09-04T03:24:01.248319Z","steps":["trace[995109373] 'read index received' (duration: 120.213342ms)","trace[995109373] 'applied index is now lower than readState.Index' (duration: 235.396µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:24:01.248428Z","caller":"traceutil/trace.go:171","msg":"trace[328350007] transaction","detail":"{read_only:false; response_revision:4371; number_of_response:1; }","duration":"193.480254ms","start":"2026-09-04T03:24:01.054909Z","end":"2026-09-04T03:24:01.24839Z","steps":["trace[328350007] 'process raft request' (duration: 193.234507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:24:01.248682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.793959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-04T03:24:01.248726Z","caller":"traceutil/trace.go:171","msg":"trace[280137125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4371; }","duration":"120.917282ms","start":"2026-09-04T03:24:01.127797Z","end":"2026-09-04T03:24:01.248714Z","steps":["trace[280137125] 'agreement among raft nodes before linearized reading' (duration: 120.664134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:26.662765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.741265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a742979e-67e8-4c87-a132-6fee4a86524e\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-09-04T03:25:26.662818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.34991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-hkssl\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-04T03:25:26.662867Z","caller":"traceutil/trace.go:171","msg":"trace[769365245] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a742979e-67e8-4c87-a132-6fee4a86524e; range_end:; response_count:1; response_revision:5533; }","duration":"137.873119ms","start":"2026-09-04T03:25:26.524975Z","end":"2026-09-04T03:25:26.662848Z","steps":["trace[769365245] 'range keys from in-memory index tree' (duration: 137.592001ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:26.662914Z","caller":"traceutil/trace.go:171","msg":"trace[628747597] range","detail":"{range_begin:/registry/pods/openstack/keepalived-hkssl; range_end:; response_count:1; response_revision:5533; }","duration":"100.473303ms","start":"2026-09-04T03:25:26.562422Z","end":"2026-09-04T03:25:26.662896Z","steps":["trace[628747597] 'range keys from in-memory index tree' (duration: 100.211206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:26.662791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.674454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:25:26.662962Z","caller":"traceutil/trace.go:171","msg":"trace[628035581] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5533; }","duration":"192.89388ms","start":"2026-09-04T03:25:26.470055Z","end":"2026-09-04T03:25:26.662949Z","steps":["trace[628035581] 'range keys from in-memory index tree' (duration: 192.547701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:26.663279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.216484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a742979e-67e8-4c87-a132-6fee4a86524e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-04T03:25:26.663333Z","caller":"traceutil/trace.go:171","msg":"trace[1623015813] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a742979e-67e8-4c87-a132-6fee4a86524e; range_end:; response_count:1; response_revision:5533; }","duration":"129.314127ms","start":"2026-09-04T03:25:26.534006Z","end":"2026-09-04T03:25:26.66332Z","steps":["trace[1623015813] 'range keys from in-memory index tree' (duration: 128.898355ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:26.878427Z","caller":"traceutil/trace.go:171","msg":"trace[2128742085] linearizableReadLoop","detail":"{readStateIndex:5747; appliedIndex:5746; }","duration":"221.87309ms","start":"2026-09-04T03:25:26.656501Z","end":"2026-09-04T03:25:26.878374Z","steps":["trace[2128742085] 'read index received' (duration: 130.368829ms)","trace[2128742085] 'applied index is now lower than readState.Index' (duration: 91.502471ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:25:26.878449Z","caller":"traceutil/trace.go:171","msg":"trace[2128812244] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"247.222513ms","start":"2026-09-04T03:25:26.63118Z","end":"2026-09-04T03:25:26.878402Z","steps":["trace[2128812244] 'process raft request' (duration: 155.66617ms)","trace[2128812244] 'compare' (duration: 91.288885ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:25:26.878685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.168368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-4jl6g-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-09-04T03:25:26.87877Z","caller":"traceutil/trace.go:171","msg":"trace[140239087] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-4jl6g-tls; range_end:; response_count:1; response_revision:5535; }","duration":"222.304732ms","start":"2026-09-04T03:25:26.656447Z","end":"2026-09-04T03:25:26.878751Z","steps":["trace[140239087] 'agreement among raft nodes before linearized reading' (duration: 222.048364ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:26.896043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.358087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:25:26.89607Z","caller":"traceutil/trace.go:171","msg":"trace[1234466698] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"227.709709ms","start":"2026-09-04T03:25:26.668332Z","end":"2026-09-04T03:25:26.896042Z","steps":["trace[1234466698] 'process raft request' (duration: 227.451152ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:26.896128Z","caller":"traceutil/trace.go:171","msg":"trace[1209451943] range","detail":"{range_begin:/registry/configmaps/monitoring/vector; range_end:; response_count:0; response_revision:5536; }","duration":"230.47213ms","start":"2026-09-04T03:25:26.665639Z","end":"2026-09-04T03:25:26.896111Z","steps":["trace[1209451943] 'agreement among raft nodes before linearized reading' (duration: 230.346297ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:26.896177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.168993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62983"} {"level":"info","ts":"2026-09-04T03:25:26.896231Z","caller":"traceutil/trace.go:171","msg":"trace[1241870731] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5536; }","duration":"227.233645ms","start":"2026-09-04T03:25:26.668982Z","end":"2026-09-04T03:25:26.896215Z","steps":["trace[1241870731] 'agreement among raft nodes before linearized reading' (duration: 227.005439ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:27.230596Z","caller":"traceutil/trace.go:171","msg":"trace[1772086387] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"159.363438ms","start":"2026-09-04T03:25:27.071199Z","end":"2026-09-04T03:25:27.230563Z","steps":["trace[1772086387] 'process raft request' (duration: 157.163174ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:27.247053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2984} {"level":"info","ts":"2026-09-04T03:25:27.247463Z","caller":"traceutil/trace.go:171","msg":"trace[1390897813] compact","detail":"{revision:2984; response_revision:5543; }","duration":"166.178827ms","start":"2026-09-04T03:25:27.081243Z","end":"2026-09-04T03:25:27.247422Z","steps":["trace[1390897813] 'process raft request' (duration: 149.223541ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:27.247768Z","caller":"traceutil/trace.go:171","msg":"trace[1600973200] linearizableReadLoop","detail":"{readStateIndex:5756; appliedIndex:5754; }","duration":"107.029626ms","start":"2026-09-04T03:25:27.140722Z","end":"2026-09-04T03:25:27.247752Z","steps":["trace[1600973200] 'read index received' (duration: 87.695639ms)","trace[1600973200] 'applied index is now lower than readState.Index' (duration: 19.332587ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:25:27.248118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.367035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-04T03:25:27.24818Z","caller":"traceutil/trace.go:171","msg":"trace[2140194872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5543; }","duration":"107.471948ms","start":"2026-09-04T03:25:27.140687Z","end":"2026-09-04T03:25:27.248159Z","steps":["trace[2140194872] 'agreement among raft nodes before linearized reading' (duration: 107.264382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:27.248344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.294954ms","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-04T03:25:27.248426Z","caller":"traceutil/trace.go:171","msg":"trace[1562293565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5543; }","duration":"105.392567ms","start":"2026-09-04T03:25:27.143012Z","end":"2026-09-04T03:25:27.248404Z","steps":["trace[1562293565] 'agreement among raft nodes before linearized reading' (duration: 105.051237ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:27.36297Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2984,"took":"113.931647ms","hash":3096592297,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":29896704,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T03:25:27.363063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3096592297,"revision":2984,"compact-revision":-1} {"level":"info","ts":"2026-09-04T03:25:35.250519Z","caller":"traceutil/trace.go:171","msg":"trace[1368437715] transaction","detail":"{read_only:false; response_revision:5705; number_of_response:1; }","duration":"102.05755ms","start":"2026-09-04T03:25:35.148436Z","end":"2026-09-04T03:25:35.250493Z","steps":["trace[1368437715] 'process raft request' (duration: 101.869204ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:51.806092Z","caller":"traceutil/trace.go:171","msg":"trace[1727850793] linearizableReadLoop","detail":"{readStateIndex:6264; appliedIndex:6263; }","duration":"108.677323ms","start":"2026-09-04T03:25:51.697389Z","end":"2026-09-04T03:25:51.806066Z","steps":["trace[1727850793] 'read index received' (duration: 108.504849ms)","trace[1727850793] 'applied index is now lower than readState.Index' (duration: 171.374µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:25:51.806156Z","caller":"traceutil/trace.go:171","msg":"trace[1910463035] transaction","detail":"{read_only:false; response_revision:6038; number_of_response:1; }","duration":"122.222576ms","start":"2026-09-04T03:25:51.68391Z","end":"2026-09-04T03:25:51.806132Z","steps":["trace[1910463035] 'process raft request' (duration: 122.004091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:51.806313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.90084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ab82440e-2d60-4374-b55c-d550924e8886\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-04T03:25:51.80636Z","caller":"traceutil/trace.go:171","msg":"trace[555505747] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ab82440e-2d60-4374-b55c-d550924e8886; range_end:; response_count:1; response_revision:6038; }","duration":"108.999402ms","start":"2026-09-04T03:25:51.697348Z","end":"2026-09-04T03:25:51.806348Z","steps":["trace[555505747] 'agreement among raft nodes before linearized reading' (duration: 108.827767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:52.004412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.976657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16544"} {"level":"info","ts":"2026-09-04T03:25:52.004547Z","caller":"traceutil/trace.go:171","msg":"trace[120354517] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:6038; }","duration":"148.150652ms","start":"2026-09-04T03:25:51.85635Z","end":"2026-09-04T03:25:52.004501Z","steps":["trace[120354517] 'range keys from in-memory index tree' (duration: 147.65427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:52.004526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.961557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-04T03:25:52.004712Z","caller":"traceutil/trace.go:171","msg":"trace[153920866] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:6038; }","duration":"128.161621ms","start":"2026-09-04T03:25:51.876514Z","end":"2026-09-04T03:25:52.004676Z","steps":["trace[153920866] 'range keys from in-memory index tree' (duration: 127.822983ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:52.374191Z","caller":"traceutil/trace.go:171","msg":"trace[1636573963] linearizableReadLoop","detail":"{readStateIndex:6266; appliedIndex:6265; }","duration":"298.086586ms","start":"2026-09-04T03:25:52.076063Z","end":"2026-09-04T03:25:52.37415Z","steps":["trace[1636573963] 'read index received' (duration: 298.009524ms)","trace[1636573963] 'applied index is now lower than readState.Index' (duration: 75.772µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:25:52.3743Z","caller":"traceutil/trace.go:171","msg":"trace[1315533627] transaction","detail":"{read_only:false; response_revision:6040; number_of_response:1; }","duration":"348.898991ms","start":"2026-09-04T03:25:52.02539Z","end":"2026-09-04T03:25:52.374289Z","steps":["trace[1315533627] 'process raft request' (duration: 348.625965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:52.374399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:25:52.025356Z","time spent":"348.965313ms","remote":"127.0.0.1:60582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:25:52.374619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.570508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d80f3b24-fb56-4a0e-8f13-a5634b47d342\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-04T03:25:52.374685Z","caller":"traceutil/trace.go:171","msg":"trace[295174636] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d80f3b24-fb56-4a0e-8f13-a5634b47d342; range_end:; response_count:1; response_revision:6040; }","duration":"298.63908ms","start":"2026-09-04T03:25:52.076036Z","end":"2026-09-04T03:25:52.374675Z","steps":["trace[295174636] 'agreement among raft nodes before linearized reading' (duration: 298.471316ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:52.386231Z","caller":"traceutil/trace.go:171","msg":"trace[550719276] transaction","detail":"{read_only:false; response_revision:6041; number_of_response:1; }","duration":"271.789062ms","start":"2026-09-04T03:25:52.114416Z","end":"2026-09-04T03:25:52.386205Z","steps":["trace[550719276] 'process raft request' (duration: 271.542876ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:30:27.26073Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5541} {"level":"info","ts":"2026-09-04T03:30:27.353486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5541,"took":"90.800003ms","hash":3472610868,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":28708864,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-04T03:30:27.35356Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3472610868,"revision":5541,"compact-revision":2984} {"level":"info","ts":"2026-09-04T03:32:14.826802Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002402.wal"} {"level":"info","ts":"2026-09-04T03:32:18.968527Z","caller":"traceutil/trace.go:171","msg":"trace[1424696105] transaction","detail":"{read_only:false; response_revision:8918; number_of_response:1; }","duration":"493.832289ms","start":"2026-09-04T03:32:18.47466Z","end":"2026-09-04T03:32:18.968493Z","steps":["trace[1424696105] 'process raft request' (duration: 493.640474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:18.968693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:32:18.47462Z","time spent":"493.984714ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:32:18.969336Z","caller":"traceutil/trace.go:171","msg":"trace[961817069] linearizableReadLoop","detail":"{readStateIndex:9238; appliedIndex:9236; }","duration":"296.752901ms","start":"2026-09-04T03:32:18.672554Z","end":"2026-09-04T03:32:18.969307Z","steps":["trace[961817069] 'read index received' (duration: 295.767772ms)","trace[961817069] 'applied index is now lower than readState.Index' (duration: 983.919µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:32:18.969395Z","caller":"traceutil/trace.go:171","msg":"trace[195058699] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"363.501057ms","start":"2026-09-04T03:32:18.60588Z","end":"2026-09-04T03:32:18.969381Z","steps":["trace[195058699] 'process raft request' (duration: 363.2525ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:18.969466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:32:18.605854Z","time spent":"363.576529ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:32:18.969502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.935265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:32:18.969543Z","caller":"traceutil/trace.go:171","msg":"trace[1501329847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8919; }","duration":"297.03988ms","start":"2026-09-04T03:32:18.672491Z","end":"2026-09-04T03:32:18.969531Z","steps":["trace[1501329847] 'agreement among raft nodes before linearized reading' (duration: 296.953817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:18.969748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.934733ms","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-04T03:32:18.969896Z","caller":"traceutil/trace.go:171","msg":"trace[1626942494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8919; }","duration":"287.078907ms","start":"2026-09-04T03:32:18.682767Z","end":"2026-09-04T03:32:18.969846Z","steps":["trace[1626942494] 'agreement among raft nodes before linearized reading' (duration: 286.721076ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:31.171861Z","caller":"traceutil/trace.go:171","msg":"trace[142591441] transaction","detail":"{read_only:false; response_revision:8984; number_of_response:1; }","duration":"153.030138ms","start":"2026-09-04T03:32:31.018808Z","end":"2026-09-04T03:32:31.171838Z","steps":["trace[142591441] 'process raft request' (duration: 152.840512ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:31.173149Z","caller":"traceutil/trace.go:171","msg":"trace[142238238] linearizableReadLoop","detail":"{readStateIndex:9307; appliedIndex:9305; }","duration":"132.735751ms","start":"2026-09-04T03:32:31.040378Z","end":"2026-09-04T03:32:31.173114Z","steps":["trace[142238238] 'read index received' (duration: 131.348681ms)","trace[142238238] 'applied index is now lower than readState.Index' (duration: 1.38533ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:32:31.173244Z","caller":"traceutil/trace.go:171","msg":"trace[1225616737] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"146.711712ms","start":"2026-09-04T03:32:31.026498Z","end":"2026-09-04T03:32:31.17321Z","steps":["trace[1225616737] 'process raft request' (duration: 146.366401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:31.173428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.02424ms","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-04T03:32:31.173473Z","caller":"traceutil/trace.go:171","msg":"trace[1717500448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8985; }","duration":"133.156354ms","start":"2026-09-04T03:32:31.040304Z","end":"2026-09-04T03:32:31.173461Z","steps":["trace[1717500448] 'agreement among raft nodes before linearized reading' (duration: 132.954819ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:32.03178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.953329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:32:32.031851Z","caller":"traceutil/trace.go:171","msg":"trace[965380632] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:8990; }","duration":"220.081982ms","start":"2026-09-04T03:32:31.811752Z","end":"2026-09-04T03:32:32.031834Z","steps":["trace[965380632] 'count revisions from in-memory index tree' (duration: 219.845655ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:32.046159Z","caller":"traceutil/trace.go:171","msg":"trace[1685426815] transaction","detail":"{read_only:false; response_revision:8991; number_of_response:1; }","duration":"131.058523ms","start":"2026-09-04T03:32:31.915072Z","end":"2026-09-04T03:32:32.04613Z","steps":["trace[1685426815] 'process raft request' (duration: 130.875068ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:56.077526Z","caller":"traceutil/trace.go:171","msg":"trace[924378776] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:9157; }","duration":"100.010172ms","start":"2026-09-04T03:32:55.97749Z","end":"2026-09-04T03:32:56.0775Z","steps":["trace[924378776] 'agreement among raft nodes before linearized reading' (duration: 99.858677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:56.077571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.070831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-mon-endpoints\" ","response":"range_response_count:1 size:389"} {"level":"warn","ts":"2026-09-04T03:32:56.077574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.197683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-04T03:32:56.077655Z","caller":"traceutil/trace.go:171","msg":"trace[85106515] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-mon-endpoints; range_end:; response_count:1; response_revision:9157; }","duration":"104.190175ms","start":"2026-09-04T03:32:55.973441Z","end":"2026-09-04T03:32:56.077631Z","steps":["trace[85106515] 'agreement among raft nodes before linearized reading' (duration: 103.948967ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:56.077662Z","caller":"traceutil/trace.go:171","msg":"trace[1233892522] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:9157; }","duration":"106.327748ms","start":"2026-09-04T03:32:55.971314Z","end":"2026-09-04T03:32:56.077642Z","steps":["trace[1233892522] 'agreement among raft nodes before linearized reading' (duration: 106.10939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:33:00.507158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.83304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:33:00.507259Z","caller":"traceutil/trace.go:171","msg":"trace[1347752370] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:9194; }","duration":"419.033605ms","start":"2026-09-04T03:33:00.088203Z","end":"2026-09-04T03:33:00.507237Z","steps":["trace[1347752370] 'count revisions from in-memory index tree' (duration: 418.724887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:33:00.507302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:33:00.088181Z","time spent":"419.109838ms","remote":"127.0.0.1:60532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-09-04T03:33:00.507385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.47867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:33:00.507499Z","caller":"traceutil/trace.go:171","msg":"trace[517890840] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9194; }","duration":"477.689256ms","start":"2026-09-04T03:33:00.029781Z","end":"2026-09-04T03:33:00.50747Z","steps":["trace[517890840] 'count revisions from in-memory index tree' (duration: 477.365216ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:33:00.507551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:33:00.029752Z","time spent":"477.785798ms","remote":"127.0.0.1:39176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-04T03:33:00.507899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.329809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T03:33:00.508017Z","caller":"traceutil/trace.go:171","msg":"trace[869989283] transaction","detail":"{read_only:false; response_revision:9195; number_of_response:1; }","duration":"356.958406ms","start":"2026-09-04T03:33:00.151028Z","end":"2026-09-04T03:33:00.507986Z","steps":["trace[869989283] 'process raft request' (duration: 163.430821ms)","trace[869989283] 'compare' (duration: 193.187664ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:33:00.50838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:33:00.151001Z","time spent":"357.179102ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:33:04.371491Z","caller":"traceutil/trace.go:171","msg":"trace[977392877] linearizableReadLoop","detail":"{readStateIndex:9546; appliedIndex:9545; }","duration":"253.988617ms","start":"2026-09-04T03:33:04.117477Z","end":"2026-09-04T03:33:04.371465Z","steps":["trace[977392877] 'read index received' (duration: 253.843843ms)","trace[977392877] 'applied index is now lower than readState.Index' (duration: 143.734µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:33:04.371566Z","caller":"traceutil/trace.go:171","msg":"trace[1494531984] transaction","detail":"{read_only:false; response_revision:9215; number_of_response:1; }","duration":"400.974396ms","start":"2026-09-04T03:33:03.970561Z","end":"2026-09-04T03:33:04.371535Z","steps":["trace[1494531984] 'process raft request' (duration: 400.742659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:33:04.371684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.184972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-04T03:33:04.371718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:33:03.970534Z","time spent":"401.093129ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:33:04.371765Z","caller":"traceutil/trace.go:171","msg":"trace[1818780788] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:9215; }","duration":"254.329347ms","start":"2026-09-04T03:33:04.117413Z","end":"2026-09-04T03:33:04.371742Z","steps":["trace[1818780788] 'agreement among raft nodes before linearized reading' (duration: 254.172312ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:04.377156Z","caller":"traceutil/trace.go:171","msg":"trace[1505567144] transaction","detail":"{read_only:false; response_revision:9216; number_of_response:1; }","duration":"141.602132ms","start":"2026-09-04T03:33:04.235535Z","end":"2026-09-04T03:33:04.377137Z","steps":["trace[1505567144] 'process raft request' (duration: 141.482749ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:12.675555Z","caller":"traceutil/trace.go:171","msg":"trace[1113275347] transaction","detail":"{read_only:false; response_revision:9260; number_of_response:1; }","duration":"107.567214ms","start":"2026-09-04T03:33:12.56795Z","end":"2026-09-04T03:33:12.675518Z","steps":["trace[1113275347] 'process raft request' (duration: 107.351417ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:12.83962Z","caller":"traceutil/trace.go:171","msg":"trace[763869885] transaction","detail":"{read_only:false; response_revision:9261; number_of_response:1; }","duration":"158.124526ms","start":"2026-09-04T03:33:12.681468Z","end":"2026-09-04T03:33:12.839593Z","steps":["trace[763869885] 'process raft request' (duration: 157.886359ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:13.501865Z","caller":"traceutil/trace.go:171","msg":"trace[1820478506] transaction","detail":"{read_only:false; response_revision:9264; number_of_response:1; }","duration":"165.027228ms","start":"2026-09-04T03:33:13.336807Z","end":"2026-09-04T03:33:13.501834Z","steps":["trace[1820478506] 'process raft request' (duration: 164.758411ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:33:13.688402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.547614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T03:33:13.688614Z","caller":"traceutil/trace.go:171","msg":"trace[2073513138] transaction","detail":"{read_only:false; response_revision:9265; number_of_response:1; }","duration":"315.88236ms","start":"2026-09-04T03:33:13.372711Z","end":"2026-09-04T03:33:13.688594Z","steps":["trace[2073513138] 'process raft request' (duration: 208.41585ms)","trace[2073513138] 'compare' (duration: 106.368479ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:33:13.688712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.087658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-04T03:33:13.68872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:33:13.372683Z","time spent":"315.986843ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:33:13.688746Z","caller":"traceutil/trace.go:171","msg":"trace[1938112173] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:9265; }","duration":"285.125849ms","start":"2026-09-04T03:33:13.403611Z","end":"2026-09-04T03:33:13.688737Z","steps":["trace[1938112173] 'agreement among raft nodes before linearized reading' (duration: 285.058416ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:13.688545Z","caller":"traceutil/trace.go:171","msg":"trace[1662414480] linearizableReadLoop","detail":"{readStateIndex:9598; appliedIndex:9596; }","duration":"284.896672ms","start":"2026-09-04T03:33:13.403627Z","end":"2026-09-04T03:33:13.688524Z","steps":["trace[1662414480] 'read index received' (duration: 97.940392ms)","trace[1662414480] 'applied index is now lower than readState.Index' (duration: 186.9542ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:33:13.750958Z","caller":"traceutil/trace.go:171","msg":"trace[730138744] transaction","detail":"{read_only:false; response_revision:9266; number_of_response:1; }","duration":"154.748837ms","start":"2026-09-04T03:33:13.596169Z","end":"2026-09-04T03:33:13.750918Z","steps":["trace[730138744] 'process raft request' (duration: 154.629144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:33:13.751173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.775727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-04T03:33:13.750996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.484332ms","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-04T03:33:13.751252Z","caller":"traceutil/trace.go:171","msg":"trace[1553301791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9266; }","duration":"282.796841ms","start":"2026-09-04T03:33:13.468438Z","end":"2026-09-04T03:33:13.751235Z","steps":["trace[1553301791] 'agreement among raft nodes before linearized reading' (duration: 282.429991ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:13.751223Z","caller":"traceutil/trace.go:171","msg":"trace[979776138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9266; }","duration":"199.871791ms","start":"2026-09-04T03:33:13.551339Z","end":"2026-09-04T03:33:13.75121Z","steps":["trace[979776138] 'agreement among raft nodes before linearized reading' (duration: 199.727317ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:51.648692Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T03:33:51.655245Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-04T03:33:51.655358Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-04T03:34:44.558414Z","caller":"traceutil/trace.go:171","msg":"trace[2057560577] linearizableReadLoop","detail":"{readStateIndex:10491; appliedIndex:10489; }","duration":"155.175806ms","start":"2026-09-04T03:34:44.403212Z","end":"2026-09-04T03:34:44.558388Z","steps":["trace[2057560577] 'read index received' (duration: 132.528248ms)","trace[2057560577] 'applied index is now lower than readState.Index' (duration: 22.645858ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:34:44.55846Z","caller":"traceutil/trace.go:171","msg":"trace[1779598123] transaction","detail":"{read_only:false; response_revision:10132; number_of_response:1; }","duration":"183.287123ms","start":"2026-09-04T03:34:44.375113Z","end":"2026-09-04T03:34:44.5584Z","steps":["trace[1779598123] 'process raft request' (duration: 182.926542ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:34:44.558474Z","caller":"traceutil/trace.go:171","msg":"trace[1741269432] transaction","detail":"{read_only:false; response_revision:10131; number_of_response:1; }","duration":"183.731686ms","start":"2026-09-04T03:34:44.374714Z","end":"2026-09-04T03:34:44.558446Z","steps":["trace[1741269432] 'process raft request' (duration: 161.035336ms)","trace[1741269432] 'compare' (duration: 22.134093ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:34:44.558858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.623379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-04T03:34:44.558968Z","caller":"traceutil/trace.go:171","msg":"trace[495855094] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10133; }","duration":"155.802654ms","start":"2026-09-04T03:34:44.403137Z","end":"2026-09-04T03:34:44.55894Z","steps":["trace[495855094] 'agreement among raft nodes before linearized reading' (duration: 155.538367ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:35:27.270996Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8052} {"level":"info","ts":"2026-09-04T03:35:27.361826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8052,"took":"88.738327ms","hash":335158678,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":25268224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-04T03:35:27.361894Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":335158678,"revision":8052,"compact-revision":5541} {"level":"info","ts":"2026-09-04T03:38:55.950608Z","caller":"traceutil/trace.go:171","msg":"trace[1323280985] transaction","detail":"{read_only:false; response_revision:11747; number_of_response:1; }","duration":"114.887637ms","start":"2026-09-04T03:38:55.835694Z","end":"2026-09-04T03:38:55.950582Z","steps":["trace[1323280985] 'process raft request' (duration: 114.834455ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:38:55.950667Z","caller":"traceutil/trace.go:171","msg":"trace[916987706] transaction","detail":"{read_only:false; response_revision:11746; number_of_response:1; }","duration":"114.913127ms","start":"2026-09-04T03:38:55.835692Z","end":"2026-09-04T03:38:55.950605Z","steps":["trace[916987706] 'process raft request' (duration: 106.75584ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:40:27.284125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10445} {"level":"info","ts":"2026-09-04T03:40:27.371208Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10445,"took":"85.025497ms","hash":2698876669,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":26148864,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-04T03:40:27.371278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2698876669,"revision":10445,"compact-revision":8052} {"level":"info","ts":"2026-09-04T03:45:27.291942Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12587} {"level":"info","ts":"2026-09-04T03:45:27.37553Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12587,"took":"80.477292ms","hash":4151828520,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":27926528,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T03:45:27.375604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4151828520,"revision":12587,"compact-revision":10445} {"level":"warn","ts":"2026-09-04T03:47:10.865236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.113546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-04T03:47:10.86533Z","caller":"traceutil/trace.go:171","msg":"trace[1928327587] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15270; }","duration":"154.215939ms","start":"2026-09-04T03:47:10.711096Z","end":"2026-09-04T03:47:10.865312Z","steps":["trace[1928327587] 'range keys from in-memory index tree' (duration: 153.973632ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:11.851473Z","caller":"traceutil/trace.go:171","msg":"trace[1725279720] linearizableReadLoop","detail":"{readStateIndex:15815; appliedIndex:15814; }","duration":"178.369554ms","start":"2026-09-04T03:47:11.67308Z","end":"2026-09-04T03:47:11.851449Z","steps":["trace[1725279720] 'read index received' (duration: 178.23385ms)","trace[1725279720] 'applied index is now lower than readState.Index' (duration: 134.474µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:47:11.851609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.520859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:47:11.851669Z","caller":"traceutil/trace.go:171","msg":"trace[1495987435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15277; }","duration":"178.663243ms","start":"2026-09-04T03:47:11.672996Z","end":"2026-09-04T03:47:11.851659Z","steps":["trace[1495987435] 'agreement among raft nodes before linearized reading' (duration: 178.56435ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:11.8516Z","caller":"traceutil/trace.go:171","msg":"trace[1578026258] transaction","detail":"{read_only:false; response_revision:15277; number_of_response:1; }","duration":"523.057662ms","start":"2026-09-04T03:47:11.328454Z","end":"2026-09-04T03:47:11.851512Z","steps":["trace[1578026258] 'process raft request' (duration: 522.845806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:11.851816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:11.328427Z","time spent":"523.285169ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:47:35.343835Z","caller":"traceutil/trace.go:171","msg":"trace[1034889891] transaction","detail":"{read_only:false; response_revision:15593; number_of_response:1; }","duration":"343.596627ms","start":"2026-09-04T03:47:35.000208Z","end":"2026-09-04T03:47:35.343805Z","steps":["trace[1034889891] 'process raft request' (duration: 343.449852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:35.343992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:35.000179Z","time spent":"343.737961ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:47:35.415704Z","caller":"traceutil/trace.go:171","msg":"trace[1712334897] transaction","detail":"{read_only:false; response_revision:15594; number_of_response:1; }","duration":"392.220487ms","start":"2026-09-04T03:47:35.023436Z","end":"2026-09-04T03:47:35.415656Z","steps":["trace[1712334897] 'process raft request' (duration: 391.933329ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:35.41574Z","caller":"traceutil/trace.go:171","msg":"trace[1456130865] linearizableReadLoop","detail":"{readStateIndex:16145; appliedIndex:16142; }","duration":"369.515544ms","start":"2026-09-04T03:47:35.046195Z","end":"2026-09-04T03:47:35.415711Z","steps":["trace[1456130865] 'read index received' (duration: 297.462187ms)","trace[1456130865] 'applied index is now lower than readState.Index' (duration: 72.052076ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:47:35.415821Z","caller":"traceutil/trace.go:171","msg":"trace[509104518] transaction","detail":"{read_only:false; response_revision:15596; number_of_response:1; }","duration":"363.535909ms","start":"2026-09-04T03:47:35.052269Z","end":"2026-09-04T03:47:35.415805Z","steps":["trace[509104518] 'process raft request' (duration: 363.366264ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:35.41584Z","caller":"traceutil/trace.go:171","msg":"trace[204561096] transaction","detail":"{read_only:false; response_revision:15595; number_of_response:1; }","duration":"381.198725ms","start":"2026-09-04T03:47:35.034625Z","end":"2026-09-04T03:47:35.415824Z","steps":["trace[204561096] 'process raft request' (duration: 380.929777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:35.415867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:35.023414Z","time spent":"392.365672ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:47:35.415918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:35.034568Z","time spent":"381.309898ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:47:35.415938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.52049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:47:35.415989Z","caller":"traceutil/trace.go:171","msg":"trace[1390784522] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:15596; }","duration":"112.627253ms","start":"2026-09-04T03:47:35.303346Z","end":"2026-09-04T03:47:35.415973Z","steps":["trace[1390784522] 'agreement among raft nodes before linearized reading' (duration: 112.52754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:35.416047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.830682ms","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-04T03:47:35.416128Z","caller":"traceutil/trace.go:171","msg":"trace[869648760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15596; }","duration":"369.953896ms","start":"2026-09-04T03:47:35.046156Z","end":"2026-09-04T03:47:35.41611Z","steps":["trace[869648760] 'agreement among raft nodes before linearized reading' (duration: 369.713869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:35.416161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:35.046138Z","time spent":"370.015998ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-04T03:47:35.416074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.816801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-nova-server-0.18d2018094ae775b\" ","response":"range_response_count:1 size:1001"} {"level":"info","ts":"2026-09-04T03:47:35.416273Z","caller":"traceutil/trace.go:171","msg":"trace[1136335067] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-nova-server-0.18d2018094ae775b; range_end:; response_count:1; response_revision:15596; }","duration":"110.018446ms","start":"2026-09-04T03:47:35.306202Z","end":"2026-09-04T03:47:35.416221Z","steps":["trace[1136335067] 'agreement among raft nodes before linearized reading' (duration: 109.79521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:35.416403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:35.052259Z","time spent":"364.086365ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7680,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:47:40.353049Z","caller":"traceutil/trace.go:171","msg":"trace[1810900788] linearizableReadLoop","detail":"{readStateIndex:16189; appliedIndex:16188; }","duration":"115.159526ms","start":"2026-09-04T03:47:40.237867Z","end":"2026-09-04T03:47:40.353027Z","steps":["trace[1810900788] 'read index received' (duration: 114.996022ms)","trace[1810900788] 'applied index is now lower than readState.Index' (duration: 162.484µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:47:40.353217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.313331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:47:40.353269Z","caller":"traceutil/trace.go:171","msg":"trace[501899883] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:15638; }","duration":"115.432225ms","start":"2026-09-04T03:47:40.237819Z","end":"2026-09-04T03:47:40.353251Z","steps":["trace[501899883] 'agreement among raft nodes before linearized reading' (duration: 115.296761ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:40.353357Z","caller":"traceutil/trace.go:171","msg":"trace[1515724560] transaction","detail":"{read_only:false; response_revision:15638; number_of_response:1; }","duration":"194.753154ms","start":"2026-09-04T03:47:40.158584Z","end":"2026-09-04T03:47:40.353338Z","steps":["trace[1515724560] 'process raft request' (duration: 194.271531ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:47.265966Z","caller":"traceutil/trace.go:171","msg":"trace[1585740378] transaction","detail":"{read_only:false; response_revision:16327; number_of_response:1; }","duration":"148.353737ms","start":"2026-09-04T03:48:47.117585Z","end":"2026-09-04T03:48:47.265939Z","steps":["trace[1585740378] 'process raft request' (duration: 89.813926ms)","trace[1585740378] 'compare' (duration: 58.287484ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:48:53.054198Z","caller":"traceutil/trace.go:171","msg":"trace[1383058230] linearizableReadLoop","detail":"{readStateIndex:16925; appliedIndex:16924; }","duration":"126.993644ms","start":"2026-09-04T03:48:52.927178Z","end":"2026-09-04T03:48:53.054172Z","steps":["trace[1383058230] 'read index received' (duration: 126.748677ms)","trace[1383058230] 'applied index is now lower than readState.Index' (duration: 243.567µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:48:53.054293Z","caller":"traceutil/trace.go:171","msg":"trace[704511684] transaction","detail":"{read_only:false; response_revision:16354; number_of_response:1; }","duration":"141.507187ms","start":"2026-09-04T03:48:52.912754Z","end":"2026-09-04T03:48:53.054261Z","steps":["trace[704511684] 'process raft request' (duration: 141.24989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:53.054432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.23006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-04T03:48:53.054491Z","caller":"traceutil/trace.go:171","msg":"trace[775047035] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16354; }","duration":"127.324303ms","start":"2026-09-04T03:48:52.927155Z","end":"2026-09-04T03:48:53.054479Z","steps":["trace[775047035] 'agreement among raft nodes before linearized reading' (duration: 127.157328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.289467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.004126ms","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-04T03:48:54.289551Z","caller":"traceutil/trace.go:171","msg":"trace[2090854088] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16360; }","duration":"136.137789ms","start":"2026-09-04T03:48:54.153393Z","end":"2026-09-04T03:48:54.289531Z","steps":["trace[2090854088] 'range keys from in-memory index tree' (duration: 135.886332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.289555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.53383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-04T03:48:54.289654Z","caller":"traceutil/trace.go:171","msg":"trace[835024453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16360; }","duration":"373.700836ms","start":"2026-09-04T03:48:53.915933Z","end":"2026-09-04T03:48:54.289634Z","steps":["trace[835024453] 'range keys from in-memory index tree' (duration: 373.317094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.289697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:48:53.915909Z","time spent":"373.778448ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-04T03:48:54.289687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.834326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-v9jxf\" ","response":"range_response_count:1 size:22096"} {"level":"info","ts":"2026-09-04T03:48:54.289808Z","caller":"traceutil/trace.go:171","msg":"trace[227704360] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-v9jxf; range_end:; response_count:1; response_revision:16360; }","duration":"275.00259ms","start":"2026-09-04T03:48:54.014773Z","end":"2026-09-04T03:48:54.289776Z","steps":["trace[227704360] 'range keys from in-memory index tree' (duration: 274.612159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.289822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.239441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-09-04T03:48:54.289887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.214546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-09-04T03:48:54.2897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.202123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-09-04T03:48:54.289972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.988089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:48:54.289979Z","caller":"traceutil/trace.go:171","msg":"trace[101116433] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16360; }","duration":"135.498982ms","start":"2026-09-04T03:48:54.154467Z","end":"2026-09-04T03:48:54.289966Z","steps":["trace[101116433] 'range keys from in-memory index tree' (duration: 135.047579ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.290005Z","caller":"traceutil/trace.go:171","msg":"trace[1103320510] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16360; }","duration":"237.02698ms","start":"2026-09-04T03:48:54.052968Z","end":"2026-09-04T03:48:54.289995Z","steps":["trace[1103320510] 'range keys from in-memory index tree' (duration: 236.952498ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.290075Z","caller":"traceutil/trace.go:171","msg":"trace[1918068571] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16360; }","duration":"257.395176ms","start":"2026-09-04T03:48:54.03253Z","end":"2026-09-04T03:48:54.289925Z","steps":["trace[1918068571] 'range keys from in-memory index tree' (duration: 257.000724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.289476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.490423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-v9jxf\" ","response":"range_response_count:1 size:22096"} {"level":"info","ts":"2026-09-04T03:48:54.292819Z","caller":"traceutil/trace.go:171","msg":"trace[1150186780] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-v9jxf; range_end:; response_count:1; response_revision:16360; }","duration":"240.886633ms","start":"2026-09-04T03:48:54.051911Z","end":"2026-09-04T03:48:54.292798Z","steps":["trace[1150186780] 'range keys from in-memory index tree' (duration: 237.264386ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.290123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.116907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-09-04T03:48:54.289523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.003926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:48:54.28994Z","caller":"traceutil/trace.go:171","msg":"trace[982267407] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16360; }","duration":"237.279948ms","start":"2026-09-04T03:48:54.05265Z","end":"2026-09-04T03:48:54.28993Z","steps":["trace[982267407] 'range keys from in-memory index tree' (duration: 237.117063ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.293238Z","caller":"traceutil/trace.go:171","msg":"trace[1330238785] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16360; }","duration":"221.235009ms","start":"2026-09-04T03:48:54.071987Z","end":"2026-09-04T03:48:54.293222Z","steps":["trace[1330238785] 'range keys from in-memory index tree' (duration: 218.053996ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.293494Z","caller":"traceutil/trace.go:171","msg":"trace[1344064151] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16360; }","duration":"140.067475ms","start":"2026-09-04T03:48:54.153413Z","end":"2026-09-04T03:48:54.29348Z","steps":["trace[1344064151] 'range keys from in-memory index tree' (duration: 135.936884ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.458653Z","caller":"traceutil/trace.go:171","msg":"trace[1108356657] linearizableReadLoop","detail":"{readStateIndex:16932; appliedIndex:16931; }","duration":"159.330869ms","start":"2026-09-04T03:48:54.29927Z","end":"2026-09-04T03:48:54.458601Z","steps":["trace[1108356657] 'read index received' (duration: 159.094852ms)","trace[1108356657] 'applied index is now lower than readState.Index' (duration: 235.187µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:48:54.458708Z","caller":"traceutil/trace.go:171","msg":"trace[1900089647] transaction","detail":"{read_only:false; response_revision:16361; number_of_response:1; }","duration":"163.524781ms","start":"2026-09-04T03:48:54.295161Z","end":"2026-09-04T03:48:54.458685Z","steps":["trace[1900089647] 'process raft request' (duration: 163.094989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.459608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.316498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737233"} {"level":"info","ts":"2026-09-04T03:48:54.459663Z","caller":"traceutil/trace.go:171","msg":"trace[723032374] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16361; }","duration":"160.420451ms","start":"2026-09-04T03:48:54.299229Z","end":"2026-09-04T03:48:54.45965Z","steps":["trace[723032374] 'agreement among raft nodes before linearized reading' (duration: 159.547775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.475957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.892843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:48:54.476042Z","caller":"traceutil/trace.go:171","msg":"trace[979557815] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16362; }","duration":"124.014607ms","start":"2026-09-04T03:48:54.352006Z","end":"2026-09-04T03:48:54.476021Z","steps":["trace[979557815] 'agreement among raft nodes before linearized reading' (duration: 123.878033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.476072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.314754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T03:48:54.476087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.836379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-04T03:48:54.476143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.450649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:48:54.47611Z","caller":"traceutil/trace.go:171","msg":"trace[532604593] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16362; }","duration":"125.384866ms","start":"2026-09-04T03:48:54.350716Z","end":"2026-09-04T03:48:54.476101Z","steps":["trace[532604593] 'agreement among raft nodes before linearized reading' (duration: 125.301394ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.476216Z","caller":"traceutil/trace.go:171","msg":"trace[2069208451] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16362; }","duration":"125.577032ms","start":"2026-09-04T03:48:54.35062Z","end":"2026-09-04T03:48:54.476197Z","steps":["trace[2069208451] 'agreement among raft nodes before linearized reading' (duration: 125.445518ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.475976Z","caller":"traceutil/trace.go:171","msg":"trace[1028262065] transaction","detail":"{read_only:false; response_revision:16362; number_of_response:1; }","duration":"117.475715ms","start":"2026-09-04T03:48:54.358481Z","end":"2026-09-04T03:48:54.475956Z","steps":["trace[1028262065] 'process raft request' (duration: 117.253288ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.476294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.041945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-09-04T03:48:54.476346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.650719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6lxfd\" ","response":"range_response_count:1 size:20640"} {"level":"info","ts":"2026-09-04T03:48:54.476385Z","caller":"traceutil/trace.go:171","msg":"trace[1194372828] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16362; }","duration":"126.263152ms","start":"2026-09-04T03:48:54.350099Z","end":"2026-09-04T03:48:54.476363Z","steps":["trace[1194372828] 'agreement among raft nodes before linearized reading' (duration: 125.899091ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.476403Z","caller":"traceutil/trace.go:171","msg":"trace[1004480718] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6lxfd; range_end:; response_count:1; response_revision:16362; }","duration":"132.737401ms","start":"2026-09-04T03:48:54.343652Z","end":"2026-09-04T03:48:54.476389Z","steps":["trace[1004480718] 'agreement among raft nodes before linearized reading' (duration: 132.541836ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.476156Z","caller":"traceutil/trace.go:171","msg":"trace[1948148283] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16362; }","duration":"126.930681ms","start":"2026-09-04T03:48:54.349206Z","end":"2026-09-04T03:48:54.476136Z","steps":["trace[1948148283] 'agreement among raft nodes before linearized reading' (duration: 126.838408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.476328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.952803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-09-04T03:48:54.476646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.978334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-09-04T03:48:54.476685Z","caller":"traceutil/trace.go:171","msg":"trace[1290922000] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16362; }","duration":"112.328754ms","start":"2026-09-04T03:48:54.364346Z","end":"2026-09-04T03:48:54.476674Z","steps":["trace[1290922000] 'agreement among raft nodes before linearized reading' (duration: 111.897992ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:48:54.476702Z","caller":"traceutil/trace.go:171","msg":"trace[1209876508] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16362; }","duration":"113.039456ms","start":"2026-09-04T03:48:54.363647Z","end":"2026-09-04T03:48:54.476686Z","steps":["trace[1209876508] 'agreement among raft nodes before linearized reading' (duration: 112.881571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:48:54.477176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.38985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737233"} {"level":"info","ts":"2026-09-04T03:48:54.478378Z","caller":"traceutil/trace.go:171","msg":"trace[1791847077] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:16362; }","duration":"173.595486ms","start":"2026-09-04T03:48:54.304744Z","end":"2026-09-04T03:48:54.478339Z","steps":["trace[1791847077] 'agreement among raft nodes before linearized reading' (duration: 171.116744ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:50:27.306961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14616} {"level":"info","ts":"2026-09-04T03:50:27.391494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14616,"took":"81.822659ms","hash":2391058525,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":30359552,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T03:50:27.391572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2391058525,"revision":14616,"compact-revision":12587} {"level":"info","ts":"2026-09-04T03:55:27.313855Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16941} {"level":"info","ts":"2026-09-04T03:55:27.421807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16941,"took":"104.928744ms","hash":1367418783,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":31395840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-04T03:55:27.421886Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1367418783,"revision":16941,"compact-revision":14616} {"level":"warn","ts":"2026-09-04T03:55:36.966275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.369476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T03:55:36.966358Z","caller":"traceutil/trace.go:171","msg":"trace[795656019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19221; }","duration":"295.492109ms","start":"2026-09-04T03:55:36.670849Z","end":"2026-09-04T03:55:36.966341Z","steps":["trace[795656019] 'range keys from in-memory index tree' (duration: 295.302524ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:55:37.406535Z","caller":"traceutil/trace.go:171","msg":"trace[656953268] transaction","detail":"{read_only:false; response_revision:19222; number_of_response:1; }","duration":"177.934438ms","start":"2026-09-04T03:55:37.228581Z","end":"2026-09-04T03:55:37.406516Z","steps":["trace[656953268] 'process raft request' (duration: 177.802874ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:55:37.720867Z","caller":"traceutil/trace.go:171","msg":"trace[1732817205] linearizableReadLoop","detail":"{readStateIndex:19893; appliedIndex:19890; }","duration":"420.467065ms","start":"2026-09-04T03:55:37.300384Z","end":"2026-09-04T03:55:37.720851Z","steps":["trace[1732817205] 'read index received' (duration: 106.044626ms)","trace[1732817205] 'applied index is now lower than readState.Index' (duration: 314.421699ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:55:37.720952Z","caller":"traceutil/trace.go:171","msg":"trace[1504235645] transaction","detail":"{read_only:false; response_revision:19224; number_of_response:1; }","duration":"440.597452ms","start":"2026-09-04T03:55:37.280349Z","end":"2026-09-04T03:55:37.720946Z","steps":["trace[1504235645] 'process raft request' (duration: 440.474187ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:55:37.720949Z","caller":"traceutil/trace.go:171","msg":"trace[1941555237] transaction","detail":"{read_only:false; response_revision:19223; number_of_response:1; }","duration":"489.66798ms","start":"2026-09-04T03:55:37.231251Z","end":"2026-09-04T03:55:37.720918Z","steps":["trace[1941555237] 'process raft request' (duration: 440.344324ms)","trace[1941555237] 'compare' (duration: 49.122299ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:55:37.721028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:55:37.280319Z","time spent":"440.648343ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:55:37.721075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:55:37.231225Z","time spent":"489.785203ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:55:37.721209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.826217ms","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-04T03:55:37.72123Z","caller":"traceutil/trace.go:171","msg":"trace[1697736807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19224; }","duration":"420.888398ms","start":"2026-09-04T03:55:37.300334Z","end":"2026-09-04T03:55:37.721223Z","steps":["trace[1697736807] 'agreement among raft nodes before linearized reading' (duration: 420.812536ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:55:37.721246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:55:37.300313Z","time spent":"420.929529ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-04T03:55:37.721321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.705991ms","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-04T03:55:37.721337Z","caller":"traceutil/trace.go:171","msg":"trace[1936620902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19224; }","duration":"389.759783ms","start":"2026-09-04T03:55:37.331573Z","end":"2026-09-04T03:55:37.721333Z","steps":["trace[1936620902] 'agreement among raft nodes before linearized reading' (duration: 389.714381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:55:37.721351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:55:37.331557Z","time spent":"389.790844ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-04T03:55:37.721625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.169643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-09-04T03:55:37.721651Z","caller":"traceutil/trace.go:171","msg":"trace[744187103] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:19224; }","duration":"169.238614ms","start":"2026-09-04T03:55:37.552405Z","end":"2026-09-04T03:55:37.721644Z","steps":["trace[744187103] 'agreement among raft nodes before linearized reading' (duration: 169.174463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:55:37.721781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.115454ms","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-04T03:55:37.721803Z","caller":"traceutil/trace.go:171","msg":"trace[1941859050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19224; }","duration":"333.166416ms","start":"2026-09-04T03:55:37.388631Z","end":"2026-09-04T03:55:37.721798Z","steps":["trace[1941859050] 'agreement among raft nodes before linearized reading' (duration: 333.098944ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:55:37.721817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:55:37.388613Z","time spent":"333.201156ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-04T03:55:43.835425Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T03:55:43.841793Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-04T03:55:43.841885Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-04T04:00:27.319972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19142} {"level":"info","ts":"2026-09-04T04:00:27.421636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19142,"took":"97.93268ms","hash":3185389597,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":34385920,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-04T04:00:27.421703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3185389597,"revision":19142,"compact-revision":16941} {"level":"info","ts":"2026-09-04T04:01:28.612822Z","caller":"traceutil/trace.go:171","msg":"trace[1955438232] transaction","detail":"{read_only:false; response_revision:22024; number_of_response:1; }","duration":"147.396239ms","start":"2026-09-04T04:01:28.465399Z","end":"2026-09-04T04:01:28.612796Z","steps":["trace[1955438232] 'process raft request' (duration: 147.248475ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:01:49.637303Z","caller":"traceutil/trace.go:171","msg":"trace[2031868054] transaction","detail":"{read_only:false; response_revision:22134; number_of_response:1; }","duration":"174.682954ms","start":"2026-09-04T04:01:49.462584Z","end":"2026-09-04T04:01:49.637267Z","steps":["trace[2031868054] 'process raft request' (duration: 174.509169ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:01:55.598521Z","caller":"traceutil/trace.go:171","msg":"trace[1828292382] transaction","detail":"{read_only:false; response_revision:22161; number_of_response:1; }","duration":"369.787021ms","start":"2026-09-04T04:01:55.228677Z","end":"2026-09-04T04:01:55.598464Z","steps":["trace[1828292382] 'process raft request' (duration: 369.612086ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:01:55.598723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T04:01:55.228647Z","time spent":"369.958616ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T04:01:55.884984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.908067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T04:01:55.885171Z","caller":"traceutil/trace.go:171","msg":"trace[1989233471] transaction","detail":"{read_only:false; response_revision:22163; number_of_response:1; }","duration":"226.081979ms","start":"2026-09-04T04:01:55.659071Z","end":"2026-09-04T04:01:55.885153Z","steps":["trace[1989233471] 'process raft request' (duration: 226.025257ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:01:55.885164Z","caller":"traceutil/trace.go:171","msg":"trace[1023335045] transaction","detail":"{read_only:false; response_revision:22162; number_of_response:1; }","duration":"653.116546ms","start":"2026-09-04T04:01:55.231998Z","end":"2026-09-04T04:01:55.885114Z","steps":["trace[1023335045] 'process raft request' (duration: 497.982702ms)","trace[1023335045] 'compare' (duration: 154.760963ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T04:01:55.885203Z","caller":"traceutil/trace.go:171","msg":"trace[413049679] linearizableReadLoop","detail":"{readStateIndex:22925; appliedIndex:22924; }","duration":"323.38102ms","start":"2026-09-04T04:01:55.561795Z","end":"2026-09-04T04:01:55.885176Z","steps":["trace[413049679] 'read index received' (duration: 36.74388ms)","trace[413049679] 'applied index is now lower than readState.Index' (duration: 286.63434ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T04:01:55.885347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.531875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-04T04:01:55.885367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T04:01:55.231975Z","time spent":"653.283561ms","remote":"127.0.0.1:60654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T04:01:55.885439Z","caller":"traceutil/trace.go:171","msg":"trace[1334902304] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:22163; }","duration":"323.669569ms","start":"2026-09-04T04:01:55.561749Z","end":"2026-09-04T04:01:55.885418Z","steps":["trace[1334902304] 'agreement among raft nodes before linearized reading' (duration: 323.494803ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:01:55.885525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T04:01:55.561727Z","time spent":"323.784033ms","remote":"127.0.0.1:41470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-09-04T04:01:55.885545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.618457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T04:01:55.885611Z","caller":"traceutil/trace.go:171","msg":"trace[988888354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22163; }","duration":"212.734311ms","start":"2026-09-04T04:01:55.672841Z","end":"2026-09-04T04:01:55.885576Z","steps":["trace[988888354] 'agreement among raft nodes before linearized reading' (duration: 212.540745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:01:55.886011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.060511ms","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-04T04:01:55.886052Z","caller":"traceutil/trace.go:171","msg":"trace[621398079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22163; }","duration":"212.141193ms","start":"2026-09-04T04:01:55.6739Z","end":"2026-09-04T04:01:55.886041Z","steps":["trace[621398079] 'agreement among raft nodes before linearized reading' (duration: 211.992279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:01:55.886066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.867003ms","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-04T04:01:55.886118Z","caller":"traceutil/trace.go:171","msg":"trace[1792965749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22163; }","duration":"191.977327ms","start":"2026-09-04T04:01:55.694122Z","end":"2026-09-04T04:01:55.8861Z","steps":["trace[1792965749] 'agreement among raft nodes before linearized reading' (duration: 191.794091ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:01:56.095257Z","caller":"traceutil/trace.go:171","msg":"trace[1687164575] transaction","detail":"{read_only:false; response_revision:22166; number_of_response:1; }","duration":"204.408268ms","start":"2026-09-04T04:01:55.890824Z","end":"2026-09-04T04:01:56.095232Z","steps":["trace[1687164575] 'process raft request' (duration: 204.359847ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:01:56.095361Z","caller":"traceutil/trace.go:171","msg":"trace[1762439480] transaction","detail":"{read_only:false; response_revision:22165; number_of_response:1; }","duration":"205.13805ms","start":"2026-09-04T04:01:55.890191Z","end":"2026-09-04T04:01:56.095329Z","steps":["trace[1762439480] 'process raft request' (duration: 182.628704ms)","trace[1762439480] 'compare' (duration: 22.209946ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T04:01:56.185388Z","caller":"traceutil/trace.go:171","msg":"trace[668958651] linearizableReadLoop","detail":"{readStateIndex:22930; appliedIndex:22927; }","duration":"133.34346ms","start":"2026-09-04T04:01:56.052015Z","end":"2026-09-04T04:01:56.185358Z","steps":["trace[668958651] 'read index received' (duration: 20.815014ms)","trace[668958651] 'applied index is now lower than readState.Index' (duration: 112.527056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T04:01:56.18569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.650679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-04T04:01:56.185735Z","caller":"traceutil/trace.go:171","msg":"trace[549096433] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:22167; }","duration":"133.755152ms","start":"2026-09-04T04:01:56.051967Z","end":"2026-09-04T04:01:56.185722Z","steps":["trace[549096433] 'agreement among raft nodes before linearized reading' (duration: 133.524915ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:01:56.18601Z","caller":"traceutil/trace.go:171","msg":"trace[1571684494] transaction","detail":"{read_only:false; response_revision:22167; number_of_response:1; }","duration":"164.61478ms","start":"2026-09-04T04:01:56.021384Z","end":"2026-09-04T04:01:56.185999Z","steps":["trace[1571684494] 'process raft request' (duration: 163.816207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:01:56.600241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.894648ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-04T04:05:27.327282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21654} {"level":"info","ts":"2026-09-04T04:05:27.449473Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21654,"took":"118.678786ms","hash":4167918560,"current-db-size-bytes":54767616,"current-db-size":"55 MB","current-db-size-in-use-bytes":31039488,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-04T04:05:27.449561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4167918560,"revision":21654,"compact-revision":19142} {"level":"info","ts":"2026-09-04T04:06:04.858038Z","caller":"traceutil/trace.go:171","msg":"trace[2146895205] linearizableReadLoop","detail":"{readStateIndex:25565; appliedIndex:25564; }","duration":"274.120721ms","start":"2026-09-04T04:06:04.583893Z","end":"2026-09-04T04:06:04.858014Z","steps":["trace[2146895205] 'read index received' (duration: 273.977757ms)","trace[2146895205] 'applied index is now lower than readState.Index' (duration: 142.074µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T04:06:04.858075Z","caller":"traceutil/trace.go:171","msg":"trace[1749429379] transaction","detail":"{read_only:false; response_revision:24725; number_of_response:1; }","duration":"282.851013ms","start":"2026-09-04T04:06:04.575201Z","end":"2026-09-04T04:06:04.858052Z","steps":["trace[1749429379] 'process raft request' (duration: 282.672497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:06:04.858344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.405129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-09-04T04:06:04.858387Z","caller":"traceutil/trace.go:171","msg":"trace[1648320646] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:24725; }","duration":"274.539754ms","start":"2026-09-04T04:06:04.583836Z","end":"2026-09-04T04:06:04.858375Z","steps":["trace[1648320646] 'agreement among raft nodes before linearized reading' (duration: 274.299447ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:06:04.911525Z","caller":"traceutil/trace.go:171","msg":"trace[1434124144] transaction","detail":"{read_only:false; response_revision:24726; number_of_response:1; }","duration":"201.478304ms","start":"2026-09-04T04:06:04.710019Z","end":"2026-09-04T04:06:04.911497Z","steps":["trace[1434124144] 'process raft request' (duration: 168.095578ms)","trace[1434124144] 'compare' (duration: 33.121187ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T04:06:04.911682Z","caller":"traceutil/trace.go:171","msg":"trace[2045264876] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"156.62024ms","start":"2026-09-04T04:06:04.755038Z","end":"2026-09-04T04:06:04.911659Z","steps":["trace[2045264876] 'process raft request' (duration: 156.380833ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:06:04.911739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.510362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T04:06:04.911812Z","caller":"traceutil/trace.go:171","msg":"trace[617848651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24727; }","duration":"239.630315ms","start":"2026-09-04T04:06:04.672163Z","end":"2026-09-04T04:06:04.911793Z","steps":["trace[617848651] 'agreement among raft nodes before linearized reading' (duration: 239.45053ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:08:57.513377Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a30.wal"} {"level":"info","ts":"2026-09-04T04:10:27.333969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23904} {"level":"info","ts":"2026-09-04T04:10:27.4524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23904,"took":"114.320291ms","hash":219013246,"current-db-size-bytes":56455168,"current-db-size":"56 MB","current-db-size-in-use-bytes":42688512,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-04T04:10:27.452478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":219013246,"revision":23904,"compact-revision":21654} {"level":"info","ts":"2026-09-04T04:15:04.369288Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"401ca643156fc5da","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T04:15:04.37676Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-04T04:15:04.376878Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-04T04:15:27.342223Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26995} {"level":"info","ts":"2026-09-04T04:15:27.484272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26995,"took":"138.423061ms","hash":847578677,"current-db-size-bytes":56455168,"current-db-size":"56 MB","current-db-size-in-use-bytes":30056448,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T04:15:27.484343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":847578677,"revision":26995,"compact-revision":23904}