{"level":"warn","ts":"2026-09-08T22:47:02.472492Z","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-08T22:47:02.472596Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.118: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.118:2380","--initial-cluster=instance=https://162.253.55.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.118: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-08T22:47:02.472694Z","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-08T22:47:02.472722Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-09-08T22:47:02.472756Z","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-08T22:47:02.473885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"]} {"level":"info","ts":"2026-09-08T22:47:02.474006Z","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":8,"max-cpu-available":8,"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.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.118: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-08T22:47:02.479111Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.137306ms"} {"level":"info","ts":"2026-09-08T22:47:02.489889Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39c975536cfc1086","cluster-id":"ac0a95643fc422f0"} {"level":"info","ts":"2026-09-08T22:47:02.490034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T22:47:02.490066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 0"} {"level":"info","ts":"2026-09-08T22:47:02.490084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39c975536cfc1086 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T22:47:02.490099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 1"} {"level":"info","ts":"2026-09-08T22:47:02.490152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"warn","ts":"2026-09-08T22:47:02.499564Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T22:47:02.501266Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T22:47:02.502482Z","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-08T22:47:02.504675Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39c975536cfc1086","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T22:47:02.504771Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39c975536cfc1086","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T22:47:02.507758Z","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-08T22:47:02.507851Z","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-08T22:47:02.507881Z","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-08T22:47:02.513847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"info","ts":"2026-09-08T22:47:02.513956Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","added-peer-id":"39c975536cfc1086","added-peer-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-09-08T22:47:02.514285Z","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-08T22:47:02.51436Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-09-08T22:47:02.514384Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-09-08T22:47:02.514514Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39c975536cfc1086","initial-advertise-peer-urls":["https://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T22:47:02.514551Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T22:47:02.991665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T22:47:02.991738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T22:47:02.991756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgPreVoteResp from 39c975536cfc1086 at term 1"} {"level":"info","ts":"2026-09-08T22:47:02.991775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became candidate at term 2"} {"level":"info","ts":"2026-09-08T22:47:02.991782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgVoteResp from 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-09-08T22:47:02.991791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became leader at term 2"} {"level":"info","ts":"2026-09-08T22:47:02.991801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39c975536cfc1086 elected leader 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-09-08T22:47:02.992715Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39c975536cfc1086","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.118:2379]}","request-path":"/0/members/39c975536cfc1086/attributes","cluster-id":"ac0a95643fc422f0","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T22:47:02.992747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:47:02.992802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T22:47:02.992782Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:02.993987Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:02.994137Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:02.994177Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T22:47:02.995052Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T22:47:02.995142Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T22:47:02.996167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.118:2379"} {"level":"info","ts":"2026-09-08T22:47:02.997208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T22:47:20.890948Z","caller":"traceutil/trace.go:171","msg":"trace[1301755955] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"128.392815ms","start":"2026-09-08T22:47:20.762535Z","end":"2026-09-08T22:47:20.890928Z","steps":["trace[1301755955] 'process raft request' (duration: 128.02859ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:47:21.011994Z","caller":"traceutil/trace.go:171","msg":"trace[2069739566] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"117.55614ms","start":"2026-09-08T22:47:20.894412Z","end":"2026-09-08T22:47:21.011968Z","steps":["trace[2069739566] 'process raft request' (duration: 100.328635ms)","trace[2069739566] 'compare' (duration: 17.130264ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:47:39.98168Z","caller":"traceutil/trace.go:171","msg":"trace[260539886] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"100.249182ms","start":"2026-09-08T22:47:39.881398Z","end":"2026-09-08T22:47:39.981647Z","steps":["trace[260539886] 'process raft request' (duration: 100.038529ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:47:46.199731Z","caller":"traceutil/trace.go:171","msg":"trace[418670712] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"165.575778ms","start":"2026-09-08T22:47:46.034122Z","end":"2026-09-08T22:47:46.199697Z","steps":["trace[418670712] 'read index received' (duration: 165.306956ms)","trace[418670712] 'applied index is now lower than readState.Index' (duration: 267.732µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:47:46.200194Z","caller":"traceutil/trace.go:171","msg":"trace[1847007268] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"172.581808ms","start":"2026-09-08T22:47:46.027522Z","end":"2026-09-08T22:47:46.200104Z","steps":["trace[1847007268] 'process raft request' (duration: 172.028723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:47:46.200237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.114054ms","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-08T22:47:46.200335Z","caller":"traceutil/trace.go:171","msg":"trace[485776570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"166.283486ms","start":"2026-09-08T22:47:46.034039Z","end":"2026-09-08T22:47:46.200322Z","steps":["trace[485776570] 'agreement among raft nodes before linearized reading' (duration: 166.061933ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:47:46.529192Z","caller":"traceutil/trace.go:171","msg":"trace[213770773] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"470.436982ms","start":"2026-09-08T22:47:46.058728Z","end":"2026-09-08T22:47:46.529165Z","steps":["trace[213770773] 'process raft request' (duration: 469.298321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:47:46.529357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.551392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.118\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-08T22:47:46.529427Z","caller":"traceutil/trace.go:171","msg":"trace[702568824] range","detail":"{range_begin:/registry/masterleases/162.253.55.118; range_end:; response_count:1; response_revision:591; }","duration":"376.645842ms","start":"2026-09-08T22:47:46.152761Z","end":"2026-09-08T22:47:46.529407Z","steps":["trace[702568824] 'agreement among raft nodes before linearized reading' (duration: 376.482621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:47:46.529507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:47:46.152655Z","time spent":"376.837563ms","remote":"127.0.0.1:34956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.118\" "} {"level":"info","ts":"2026-09-08T22:47:46.529113Z","caller":"traceutil/trace.go:171","msg":"trace[1684874337] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"329.271688ms","start":"2026-09-08T22:47:46.199804Z","end":"2026-09-08T22:47:46.529076Z","steps":["trace[1684874337] 'read index received' (duration: 328.153247ms)","trace[1684874337] 'applied index is now lower than readState.Index' (duration: 1.116931ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:47:46.530891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:47:46.058692Z","time spent":"470.696724ms","remote":"127.0.0.1:34994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:47:46.539494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.382748ms","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-08T22:47:46.539569Z","caller":"traceutil/trace.go:171","msg":"trace[692353173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"305.49946ms","start":"2026-09-08T22:47:46.234051Z","end":"2026-09-08T22:47:46.53955Z","steps":["trace[692353173] 'agreement among raft nodes before linearized reading' (duration: 305.281158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:47:46.539613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:47:46.234034Z","time spent":"305.568441ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T22:47:46.539604Z","caller":"traceutil/trace.go:171","msg":"trace[2028487601] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"333.628451ms","start":"2026-09-08T22:47:46.205957Z","end":"2026-09-08T22:47:46.539585Z","steps":["trace[2028487601] 'process raft request' (duration: 333.219808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:47:46.539772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:47:46.205923Z","time spent":"333.749412ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:47:47.122364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.119551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:47:47.123038Z","caller":"traceutil/trace.go:171","msg":"trace[43187501] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"509.807622ms","start":"2026-09-08T22:47:46.613186Z","end":"2026-09-08T22:47:47.122994Z","steps":["trace[43187501] 'process raft request' (duration: 314.681092ms)","trace[43187501] 'compare' (duration: 192.798337ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:47:47.123234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:47:46.613132Z","time spent":"510.003834ms","remote":"127.0.0.1:35264","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-08T22:48:03.51687Z","caller":"traceutil/trace.go:171","msg":"trace[1091954357] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"276.546694ms","start":"2026-09-08T22:48:03.240288Z","end":"2026-09-08T22:48:03.516835Z","steps":["trace[1091954357] 'read index received' (duration: 276.252072ms)","trace[1091954357] 'applied index is now lower than readState.Index' (duration: 293.212µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:48:03.516988Z","caller":"traceutil/trace.go:171","msg":"trace[1811151995] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"369.216311ms","start":"2026-09-08T22:48:03.147719Z","end":"2026-09-08T22:48:03.516935Z","steps":["trace[1811151995] 'process raft request' (duration: 368.912049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:48:03.517275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:48:03.147696Z","time spent":"369.413034ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:48:03.581234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.89727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-08T22:48:03.581282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.309014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T22:48:03.581335Z","caller":"traceutil/trace.go:171","msg":"trace[618596381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"341.098422ms","start":"2026-09-08T22:48:03.240211Z","end":"2026-09-08T22:48:03.581309Z","steps":["trace[618596381] 'agreement among raft nodes before linearized reading' (duration: 276.889639ms)","trace[618596381] 'range keys from in-memory index tree' (duration: 63.8329ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:48:03.581387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:48:03.240157Z","time spent":"341.219313ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-08T22:48:03.581389Z","caller":"traceutil/trace.go:171","msg":"trace[836843101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"295.470356ms","start":"2026-09-08T22:48:03.285889Z","end":"2026-09-08T22:48:03.581359Z","steps":["trace[836843101] 'agreement among raft nodes before linearized reading' (duration: 231.281292ms)","trace[836843101] 'range keys from in-memory index tree' (duration: 63.83445ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:49:25.87304Z","caller":"traceutil/trace.go:171","msg":"trace[51232368] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"268.492734ms","start":"2026-09-08T22:49:25.604501Z","end":"2026-09-08T22:49:25.872994Z","steps":["trace[51232368] 'read index received' (duration: 268.194483ms)","trace[51232368] 'applied index is now lower than readState.Index' (duration: 296.911µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:49:25.873139Z","caller":"traceutil/trace.go:171","msg":"trace[1195686103] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"287.630295ms","start":"2026-09-08T22:49:25.585457Z","end":"2026-09-08T22:49:25.873087Z","steps":["trace[1195686103] 'process raft request' (duration: 287.333165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:25.873381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.813886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T22:49:25.873443Z","caller":"traceutil/trace.go:171","msg":"trace[567187629] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1850; }","duration":"231.895959ms","start":"2026-09-08T22:49:25.641527Z","end":"2026-09-08T22:49:25.873423Z","steps":["trace[567187629] 'agreement among raft nodes before linearized reading' (duration: 231.713352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:25.873418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.283141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9dc49b23-cf25-415a-abaa-bdd8d6b924e5\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-08T22:49:25.873533Z","caller":"traceutil/trace.go:171","msg":"trace[1020005243] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9dc49b23-cf25-415a-abaa-bdd8d6b924e5; range_end:; response_count:1; response_revision:1850; }","duration":"107.469088ms","start":"2026-09-08T22:49:25.766031Z","end":"2026-09-08T22:49:25.873501Z","steps":["trace[1020005243] 'agreement among raft nodes before linearized reading' (duration: 107.25519ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:25.873254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.725861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T22:49:25.873875Z","caller":"traceutil/trace.go:171","msg":"trace[1206269091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"269.372406ms","start":"2026-09-08T22:49:25.604447Z","end":"2026-09-08T22:49:25.873819Z","steps":["trace[1206269091] 'agreement among raft nodes before linearized reading' (duration: 268.723102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:26.19389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.894785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:49:26.19439Z","caller":"traceutil/trace.go:171","msg":"trace[256140126] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"115.455539ms","start":"2026-09-08T22:49:26.078883Z","end":"2026-09-08T22:49:26.194338Z","steps":["trace[256140126] 'process raft request' (duration: 115.172169ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:26.194382Z","caller":"traceutil/trace.go:171","msg":"trace[2042236501] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"115.932965ms","start":"2026-09-08T22:49:26.078406Z","end":"2026-09-08T22:49:26.194339Z","steps":["trace[2042236501] 'compare' (duration: 105.706339ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:29.075028Z","caller":"traceutil/trace.go:171","msg":"trace[414423795] linearizableReadLoop","detail":"{readStateIndex:1959; appliedIndex:1958; }","duration":"176.000623ms","start":"2026-09-08T22:49:28.899004Z","end":"2026-09-08T22:49:29.075004Z","steps":["trace[414423795] 'read index received' (duration: 175.799205ms)","trace[414423795] 'applied index is now lower than readState.Index' (duration: 200.478µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:49:29.075166Z","caller":"traceutil/trace.go:171","msg":"trace[1690079577] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"178.633826ms","start":"2026-09-08T22:49:28.896519Z","end":"2026-09-08T22:49:29.075153Z","steps":["trace[1690079577] 'process raft request' (duration: 178.343265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:49:29.077259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.216039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T22:49:29.077344Z","caller":"traceutil/trace.go:171","msg":"trace[1437707703] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1879; }","duration":"178.348954ms","start":"2026-09-08T22:49:28.898976Z","end":"2026-09-08T22:49:29.077325Z","steps":["trace[1437707703] 'agreement among raft nodes before linearized reading' (duration: 176.298873ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:29.347097Z","caller":"traceutil/trace.go:171","msg":"trace[1361343636] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"123.215659ms","start":"2026-09-08T22:49:29.223853Z","end":"2026-09-08T22:49:29.347069Z","steps":["trace[1361343636] 'process raft request' (duration: 123.081784ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:49:29.347265Z","caller":"traceutil/trace.go:171","msg":"trace[749942257] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1968; }","duration":"121.036722ms","start":"2026-09-08T22:49:29.226197Z","end":"2026-09-08T22:49:29.347234Z","steps":["trace[749942257] 'read index received' (duration: 90.438974ms)","trace[749942257] 'applied index is now lower than readState.Index' (duration: 30.595608ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:49:29.347389Z","caller":"traceutil/trace.go:171","msg":"trace[774862441] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"123.915132ms","start":"2026-09-08T22:49:29.223458Z","end":"2026-09-08T22:49:29.347373Z","steps":["trace[774862441] 'process raft request' (duration: 93.161129ms)","trace[774862441] 'compare' (duration: 30.139502ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:49:29.347733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.504679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:1 size:199"} {"level":"info","ts":"2026-09-08T22:49:29.347881Z","caller":"traceutil/trace.go:171","msg":"trace[532556966] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:1; response_revision:1891; }","duration":"121.653374ms","start":"2026-09-08T22:49:29.226158Z","end":"2026-09-08T22:49:29.347812Z","steps":["trace[532556966] 'agreement among raft nodes before linearized reading' (duration: 121.439997ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:50:00.546066Z","caller":"traceutil/trace.go:171","msg":"trace[1698303900] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"143.194027ms","start":"2026-09-08T22:50:00.402843Z","end":"2026-09-08T22:50:00.546037Z","steps":["trace[1698303900] 'process raft request' (duration: 142.989262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:50:00.844658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.471202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:50:00.844797Z","caller":"traceutil/trace.go:171","msg":"trace[1748970244] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"440.602121ms","start":"2026-09-08T22:50:00.404169Z","end":"2026-09-08T22:50:00.844771Z","steps":["trace[1748970244] 'process raft request' (duration: 187.893383ms)","trace[1748970244] 'compare' (duration: 252.321318ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:50:00.844925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T22:50:00.404156Z","time spent":"440.683703ms","remote":"127.0.0.1:35176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T22:50:01.1548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.974789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:50:01.154942Z","caller":"traceutil/trace.go:171","msg":"trace[949449694] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"249.05977ms","start":"2026-09-08T22:50:00.905829Z","end":"2026-09-08T22:50:01.154889Z","steps":["trace[949449694] 'process raft request' (duration: 50.908626ms)","trace[949449694] 'compare' (duration: 197.583129ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:09.305952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.689755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-09-08T22:51:09.306854Z","caller":"traceutil/trace.go:171","msg":"trace[1674476782] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2566; }","duration":"219.654364ms","start":"2026-09-08T22:51:09.087147Z","end":"2026-09-08T22:51:09.306801Z","steps":["trace[1674476782] 'range keys from in-memory index tree' (duration: 217.644985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:51:43.821155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.951124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T22:51:43.82204Z","caller":"traceutil/trace.go:171","msg":"trace[1679242706] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"272.585972ms","start":"2026-09-08T22:51:43.549403Z","end":"2026-09-08T22:51:43.821989Z","steps":["trace[1679242706] 'process raft request' (duration: 78.689752ms)","trace[1679242706] 'compare' (duration: 192.443465ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:43.822096Z","caller":"traceutil/trace.go:171","msg":"trace[668846437] linearizableReadLoop","detail":"{readStateIndex:2867; appliedIndex:2866; }","duration":"248.746996ms","start":"2026-09-08T22:51:43.573266Z","end":"2026-09-08T22:51:43.822013Z","steps":["trace[668846437] 'read index received' (duration: 54.681972ms)","trace[668846437] 'applied index is now lower than readState.Index' (duration: 194.059224ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:51:43.822359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.074882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T22:51:43.822399Z","caller":"traceutil/trace.go:171","msg":"trace[1166092552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2758; }","duration":"249.184264ms","start":"2026-09-08T22:51:43.573203Z","end":"2026-09-08T22:51:43.822388Z","steps":["trace[1166092552] 'agreement among raft nodes before linearized reading' (duration: 248.981811ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:43.822447Z","caller":"traceutil/trace.go:171","msg":"trace[945460400] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"162.918521ms","start":"2026-09-08T22:51:43.659061Z","end":"2026-09-08T22:51:43.821979Z","steps":["trace[945460400] 'process raft request' (duration: 162.83014ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:51:43.995279Z","caller":"traceutil/trace.go:171","msg":"trace[1376176314] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"168.300614ms","start":"2026-09-08T22:51:43.82693Z","end":"2026-09-08T22:51:43.995231Z","steps":["trace[1376176314] 'process raft request' (duration: 125.367437ms)","trace[1376176314] 'compare' (duration: 42.748294ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:51:43.998261Z","caller":"traceutil/trace.go:171","msg":"trace[1592506608] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"103.578946ms","start":"2026-09-08T22:51:43.894636Z","end":"2026-09-08T22:51:43.998215Z","steps":["trace[1592506608] 'process raft request' (duration: 103.423653ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:27.107296Z","caller":"traceutil/trace.go:171","msg":"trace[1382957328] linearizableReadLoop","detail":"{readStateIndex:5037; appliedIndex:5036; }","duration":"125.274311ms","start":"2026-09-08T22:56:26.981989Z","end":"2026-09-08T22:56:27.107264Z","steps":["trace[1382957328] 'read index received' (duration: 125.20628ms)","trace[1382957328] 'applied index is now lower than readState.Index' (duration: 66.431µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:56:27.107359Z","caller":"traceutil/trace.go:171","msg":"trace[1520455706] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"130.481102ms","start":"2026-09-08T22:56:26.976838Z","end":"2026-09-08T22:56:27.107319Z","steps":["trace[1520455706] 'process raft request' (duration: 130.198896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:27.107552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.534537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1370"} {"level":"info","ts":"2026-09-08T22:56:27.107597Z","caller":"traceutil/trace.go:171","msg":"trace[349159573] range","detail":"{range_begin:/registry/clusterroles/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4845; }","duration":"125.614309ms","start":"2026-09-08T22:56:26.981971Z","end":"2026-09-08T22:56:27.107586Z","steps":["trace[349159573] 'agreement among raft nodes before linearized reading' (duration: 125.417784ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:27.13411Z","caller":"traceutil/trace.go:171","msg":"trace[1354907395] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"101.065411ms","start":"2026-09-08T22:56:27.033024Z","end":"2026-09-08T22:56:27.13409Z","steps":["trace[1354907395] 'process raft request' (duration: 100.791935ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:27.134283Z","caller":"traceutil/trace.go:171","msg":"trace[310511215] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"106.598319ms","start":"2026-09-08T22:56:27.027669Z","end":"2026-09-08T22:56:27.134267Z","steps":["trace[310511215] 'process raft request' (duration: 105.980187ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:31.64634Z","caller":"traceutil/trace.go:171","msg":"trace[1151265178] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"133.699186ms","start":"2026-09-08T22:56:31.512601Z","end":"2026-09-08T22:56:31.646301Z","steps":["trace[1151265178] 'process raft request' (duration: 133.329188ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:31.646202Z","caller":"traceutil/trace.go:171","msg":"trace[1098370027] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"124.837347ms","start":"2026-09-08T22:56:31.521336Z","end":"2026-09-08T22:56:31.646174Z","steps":["trace[1098370027] 'read index received' (duration: 124.725515ms)","trace[1098370027] 'applied index is now lower than readState.Index' (duration: 110.472µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:56:31.646754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.370119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:56:31.646897Z","caller":"traceutil/trace.go:171","msg":"trace[436111628] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:4948; }","duration":"125.556372ms","start":"2026-09-08T22:56:31.521279Z","end":"2026-09-08T22:56:31.646835Z","steps":["trace[436111628] 'agreement among raft nodes before linearized reading' (duration: 125.136973ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:37.970585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.099286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T22:56:37.970923Z","caller":"traceutil/trace.go:171","msg":"trace[1691673884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5007; }","duration":"256.490685ms","start":"2026-09-08T22:56:37.714408Z","end":"2026-09-08T22:56:37.970899Z","steps":["trace[1691673884] 'range keys from in-memory index tree' (duration: 255.76713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:37.970968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.302535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-08T22:56:37.970785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.96592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-08T22:56:37.971083Z","caller":"traceutil/trace.go:171","msg":"trace[342427134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5007; }","duration":"234.465348ms","start":"2026-09-08T22:56:37.736591Z","end":"2026-09-08T22:56:37.971056Z","steps":["trace[342427134] 'range keys from in-memory index tree' (duration: 234.016689ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:37.971197Z","caller":"traceutil/trace.go:171","msg":"trace[632702030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5007; }","duration":"227.352407ms","start":"2026-09-08T22:56:37.743803Z","end":"2026-09-08T22:56:37.971155Z","steps":["trace[632702030] 'range keys from in-memory index tree' (duration: 226.793595ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:38.085261Z","caller":"traceutil/trace.go:171","msg":"trace[990565134] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"108.428245ms","start":"2026-09-08T22:56:37.9768Z","end":"2026-09-08T22:56:38.085228Z","steps":["trace[990565134] 'process raft request' (duration: 108.364554ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:38.085508Z","caller":"traceutil/trace.go:171","msg":"trace[186480208] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"108.703542ms","start":"2026-09-08T22:56:37.97679Z","end":"2026-09-08T22:56:38.085494Z","steps":["trace[186480208] 'process raft request' (duration: 108.18083ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:38.09451Z","caller":"traceutil/trace.go:171","msg":"trace[138578153] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"117.522938ms","start":"2026-09-08T22:56:37.976942Z","end":"2026-09-08T22:56:38.094465Z","steps":["trace[138578153] 'process raft request' (duration: 116.343863ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:38.438774Z","caller":"traceutil/trace.go:171","msg":"trace[820313165] linearizableReadLoop","detail":"{readStateIndex:5223; appliedIndex:5221; }","duration":"132.449783ms","start":"2026-09-08T22:56:38.306273Z","end":"2026-09-08T22:56:38.438723Z","steps":["trace[820313165] 'read index received' (duration: 131.950742ms)","trace[820313165] 'applied index is now lower than readState.Index' (duration: 497.791µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:56:38.4388Z","caller":"traceutil/trace.go:171","msg":"trace[579723091] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"197.965009ms","start":"2026-09-08T22:56:38.240797Z","end":"2026-09-08T22:56:38.438762Z","steps":["trace[579723091] 'process raft request' (duration: 197.833467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:38.438985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.684568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T22:56:38.439046Z","caller":"traceutil/trace.go:171","msg":"trace[1678142313] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5024; }","duration":"132.810281ms","start":"2026-09-08T22:56:38.306217Z","end":"2026-09-08T22:56:38.439028Z","steps":["trace[1678142313] 'agreement among raft nodes before linearized reading' (duration: 132.672988ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:38.439429Z","caller":"traceutil/trace.go:171","msg":"trace[1912007604] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"202.176499ms","start":"2026-09-08T22:56:38.237197Z","end":"2026-09-08T22:56:38.439373Z","steps":["trace[1912007604] 'process raft request' (duration: 201.119815ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:55.424779Z","caller":"traceutil/trace.go:171","msg":"trace[557256683] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5445; }","duration":"160.227811ms","start":"2026-09-08T22:56:55.264503Z","end":"2026-09-08T22:56:55.424731Z","steps":["trace[557256683] 'read index received' (duration: 87.614395ms)","trace[557256683] 'applied index is now lower than readState.Index' (duration: 72.612046ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:56:55.425344Z","caller":"traceutil/trace.go:171","msg":"trace[573045569] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"167.316098ms","start":"2026-09-08T22:56:55.257988Z","end":"2026-09-08T22:56:55.425304Z","steps":["trace[573045569] 'process raft request' (duration: 166.573642ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:55.425453Z","caller":"traceutil/trace.go:171","msg":"trace[505417729] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"169.631746ms","start":"2026-09-08T22:56:55.255791Z","end":"2026-09-08T22:56:55.425423Z","steps":["trace[505417729] 'process raft request' (duration: 96.316676ms)","trace[505417729] 'compare' (duration: 72.118486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:56:55.425628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.103169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-170a140c-3ffe-4e5e-a34a-1ab5452e7655\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-08T22:56:55.425688Z","caller":"traceutil/trace.go:171","msg":"trace[1933125834] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-170a140c-3ffe-4e5e-a34a-1ab5452e7655; range_end:; response_count:1; response_revision:5242; }","duration":"161.219571ms","start":"2026-09-08T22:56:55.264451Z","end":"2026-09-08T22:56:55.425671Z","steps":["trace[1933125834] 'agreement among raft nodes before linearized reading' (duration: 161.041648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T22:56:55.455565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.656002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-170a140c-3ffe-4e5e-a34a-1ab5452e7655\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-08T22:56:55.455633Z","caller":"traceutil/trace.go:171","msg":"trace[1085402514] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-170a140c-3ffe-4e5e-a34a-1ab5452e7655; range_end:; response_count:1; response_revision:5243; }","duration":"121.790554ms","start":"2026-09-08T22:56:55.333826Z","end":"2026-09-08T22:56:55.455617Z","steps":["trace[1085402514] 'agreement among raft nodes before linearized reading' (duration: 121.537499ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:56:55.669951Z","caller":"traceutil/trace.go:171","msg":"trace[1913979256] linearizableReadLoop","detail":"{readStateIndex:5449; appliedIndex:5448; }","duration":"210.287898ms","start":"2026-09-08T22:56:55.459622Z","end":"2026-09-08T22:56:55.66991Z","steps":["trace[1913979256] 'read index received' (duration: 180.374178ms)","trace[1913979256] 'applied index is now lower than readState.Index' (duration: 29.91209ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T22:56:55.670021Z","caller":"traceutil/trace.go:171","msg":"trace[983158834] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"236.163445ms","start":"2026-09-08T22:56:55.433818Z","end":"2026-09-08T22:56:55.669982Z","steps":["trace[983158834] 'process raft request' (duration: 206.167193ms)","trace[983158834] 'compare' (duration: 29.710936ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T22:56:55.67102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.37346ms","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-08T22:56:55.671136Z","caller":"traceutil/trace.go:171","msg":"trace[1060289636] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5244; }","duration":"211.525154ms","start":"2026-09-08T22:56:55.459582Z","end":"2026-09-08T22:56:55.671107Z","steps":["trace[1060289636] 'agreement among raft nodes before linearized reading' (duration: 210.547304ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T22:57:03.036007Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2861} {"level":"info","ts":"2026-09-08T22:57:03.115859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2861,"took":"78.031972ms","hash":1060102237,"current-db-size-bytes":51240960,"current-db-size":"51 MB","current-db-size-in-use-bytes":27394048,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T22:57:03.115937Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1060102237,"revision":2861,"compact-revision":-1} {"level":"info","ts":"2026-09-08T23:02:03.045445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5308} {"level":"info","ts":"2026-09-08T23:02:03.134824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5308,"took":"86.886756ms","hash":1715777935,"current-db-size-bytes":51240960,"current-db-size":"51 MB","current-db-size-in-use-bytes":18673664,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-08T23:02:03.134897Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1715777935,"revision":5308,"compact-revision":2861}