{"level":"warn","ts":"2026-09-19T10:23:51.665642Z","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-19T10:23:51.665715Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.96: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.96:2380","--initial-cluster=instance=https://162.253.55.96:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.96:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.96: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-19T10:23:51.665771Z","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-19T10:23:51.665778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.96:2380"]} {"level":"info","ts":"2026-09-19T10:23:51.665794Z","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-19T10:23:51.666442Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"]} {"level":"info","ts":"2026-09-19T10:23:51.666509Z","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.96:2380"],"listen-peer-urls":["https://162.253.55.96:2380"],"advertise-client-urls":["https://162.253.55.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.96: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-19T10:23:51.671768Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.084743ms"} {"level":"info","ts":"2026-09-19T10:23:51.67804Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"64a80de7883e6886","cluster-id":"8160e60c0578d165"} {"level":"info","ts":"2026-09-19T10:23:51.678125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T10:23:51.67816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became follower at term 0"} {"level":"info","ts":"2026-09-19T10:23:51.678175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 64a80de7883e6886 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T10:23:51.67819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became follower at term 1"} {"level":"info","ts":"2026-09-19T10:23:51.678229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=(7253062487954581638)"} {"level":"warn","ts":"2026-09-19T10:23:51.688143Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T10:23:51.690175Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T10:23:51.693972Z","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-19T10:23:51.696548Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"64a80de7883e6886","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T10:23:51.69752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"64a80de7883e6886","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T10:23:51.697595Z","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-19T10:23:51.697658Z","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-19T10:23:51.697679Z","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-19T10:23:51.698486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 switched to configuration voters=(7253062487954581638)"} {"level":"info","ts":"2026-09-19T10:23:51.698609Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8160e60c0578d165","local-member-id":"64a80de7883e6886","added-peer-id":"64a80de7883e6886","added-peer-peer-urls":["https://162.253.55.96:2380"]} {"level":"info","ts":"2026-09-19T10:23:51.706085Z","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-19T10:23:51.706346Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"64a80de7883e6886","initial-advertise-peer-urls":["https://162.253.55.96:2380"],"listen-peer-urls":["https://162.253.55.96:2380"],"advertise-client-urls":["https://162.253.55.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T10:23:51.706377Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T10:23:51.707287Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.96:2380"} {"level":"info","ts":"2026-09-19T10:23:51.707334Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.96:2380"} {"level":"info","ts":"2026-09-19T10:23:51.879114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T10:23:51.879188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T10:23:51.87921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 received MsgPreVoteResp from 64a80de7883e6886 at term 1"} {"level":"info","ts":"2026-09-19T10:23:51.879226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became candidate at term 2"} {"level":"info","ts":"2026-09-19T10:23:51.879241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 received MsgVoteResp from 64a80de7883e6886 at term 2"} {"level":"info","ts":"2026-09-19T10:23:51.879255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"64a80de7883e6886 became leader at term 2"} {"level":"info","ts":"2026-09-19T10:23:51.879278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 64a80de7883e6886 elected leader 64a80de7883e6886 at term 2"} {"level":"info","ts":"2026-09-19T10:23:51.881726Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"64a80de7883e6886","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.96:2379]}","request-path":"/0/members/64a80de7883e6886/attributes","cluster-id":"8160e60c0578d165","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T10:23:51.881756Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:23:51.881874Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:23:51.882022Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:23:51.883461Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T10:23:51.883489Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T10:23:51.883888Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.96:2379"} {"level":"info","ts":"2026-09-19T10:23:51.884593Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8160e60c0578d165","local-member-id":"64a80de7883e6886","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:23:51.884756Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:23:51.88483Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:23:51.889647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T10:23:54.520339Z","caller":"traceutil/trace.go:171","msg":"trace[1501479384] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"164.917857ms","start":"2026-09-19T10:23:54.355369Z","end":"2026-09-19T10:23:54.520287Z","steps":["trace[1501479384] 'process raft request' (duration: 164.63834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:54.520529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.912461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:23:54.520607Z","caller":"traceutil/trace.go:171","msg":"trace[1949163256] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"164.051865ms","start":"2026-09-19T10:23:54.356542Z","end":"2026-09-19T10:23:54.520594Z","steps":["trace[1949163256] 'agreement among raft nodes before linearized reading' (duration: 163.86918ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:54.520358Z","caller":"traceutil/trace.go:171","msg":"trace[1425431412] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"163.602243ms","start":"2026-09-19T10:23:54.356563Z","end":"2026-09-19T10:23:54.520165Z","steps":["trace[1425431412] 'read index received' (duration: 163.376027ms)","trace[1425431412] 'applied index is now lower than readState.Index' (duration: 225.286µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:23:54.907983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.672002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-19T10:23:54.90817Z","caller":"traceutil/trace.go:171","msg":"trace[1770084871] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:72; }","duration":"381.845416ms","start":"2026-09-19T10:23:54.52624Z","end":"2026-09-19T10:23:54.908085Z","steps":["trace[1770084871] 'range keys from in-memory index tree' (duration: 381.59743ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:23:54.908305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:54.526231Z","time spent":"382.021421ms","remote":"127.0.0.1:57288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-09-19T10:23:54.909395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.130307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-19T10:23:54.909569Z","caller":"traceutil/trace.go:171","msg":"trace[1109941923] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"383.113788ms","start":"2026-09-19T10:23:54.526413Z","end":"2026-09-19T10:23:54.909527Z","steps":["trace[1109941923] 'process raft request' (duration: 130.484303ms)","trace[1109941923] 'compare' (duration: 250.990594ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:23:54.909687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:23:54.526401Z","time spent":"383.239831ms","remote":"127.0.0.1:57312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:23:55.0411Z","caller":"traceutil/trace.go:171","msg":"trace[1632579665] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"114.918918ms","start":"2026-09-19T10:23:54.926158Z","end":"2026-09-19T10:23:55.041077Z","steps":["trace[1632579665] 'process raft request' (duration: 113.120073ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:36.376639Z","caller":"traceutil/trace.go:171","msg":"trace[1619815367] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"210.108063ms","start":"2026-09-19T10:24:36.166503Z","end":"2026-09-19T10:24:36.376611Z","steps":["trace[1619815367] 'process raft request' (duration: 209.731706ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:37.081104Z","caller":"traceutil/trace.go:171","msg":"trace[600591948] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"104.060159ms","start":"2026-09-19T10:24:36.976843Z","end":"2026-09-19T10:24:37.080903Z","steps":["trace[600591948] 'process raft request' (duration: 93.470308ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:37.835586Z","caller":"traceutil/trace.go:171","msg":"trace[749744594] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"190.215137ms","start":"2026-09-19T10:24:37.645327Z","end":"2026-09-19T10:24:37.835542Z","steps":["trace[749744594] 'read index received' (duration: 188.966585ms)","trace[749744594] 'applied index is now lower than readState.Index' (duration: 1.247142ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:37.835662Z","caller":"traceutil/trace.go:171","msg":"trace[1866456821] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"297.367004ms","start":"2026-09-19T10:24:37.538268Z","end":"2026-09-19T10:24:37.835635Z","steps":["trace[1866456821] 'process raft request' (duration: 296.385267ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:37.835825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.41259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:24:37.835884Z","caller":"traceutil/trace.go:171","msg":"trace[370930885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"190.607364ms","start":"2026-09-19T10:24:37.645263Z","end":"2026-09-19T10:24:37.835871Z","steps":["trace[370930885] 'agreement among raft nodes before linearized reading' (duration: 190.42413ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:39.314279Z","caller":"traceutil/trace.go:171","msg":"trace[525073617] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"107.919312ms","start":"2026-09-19T10:24:39.206337Z","end":"2026-09-19T10:24:39.314256Z","steps":["trace[525073617] 'process raft request' (duration: 107.77882ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:39.317883Z","caller":"traceutil/trace.go:171","msg":"trace[1258389539] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"108.082814ms","start":"2026-09-19T10:24:39.20977Z","end":"2026-09-19T10:24:39.317853Z","steps":["trace[1258389539] 'process raft request' (duration: 107.914382ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:25:24.209261Z","caller":"traceutil/trace.go:171","msg":"trace[103169309] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"117.967564ms","start":"2026-09-19T10:25:24.091267Z","end":"2026-09-19T10:25:24.209235Z","steps":["trace[103169309] 'process raft request' (duration: 117.834633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:26:14.9317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.068369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:26:14.931848Z","caller":"traceutil/trace.go:171","msg":"trace[12106796] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"286.848881ms","start":"2026-09-19T10:26:14.644977Z","end":"2026-09-19T10:26:14.931826Z","steps":["trace[12106796] 'read index received' (duration: 21.289427ms)","trace[12106796] 'applied index is now lower than readState.Index' (duration: 265.557774ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:26:14.931953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.985022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:26:14.931935Z","caller":"traceutil/trace.go:171","msg":"trace[508306860] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"299.226336ms","start":"2026-09-19T10:26:14.632673Z","end":"2026-09-19T10:26:14.931899Z","steps":["trace[508306860] 'process raft request' (duration: 33.662122ms)","trace[508306860] 'compare' (duration: 264.908687ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:26:14.931985Z","caller":"traceutil/trace.go:171","msg":"trace[1515060849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1891; }","duration":"287.069703ms","start":"2026-09-19T10:26:14.644905Z","end":"2026-09-19T10:26:14.931975Z","steps":["trace[1515060849] 'agreement among raft nodes before linearized reading' (duration: 287.008912ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:26:14.945981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.269109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-108ce55d-f09b-4415-a6f6-f6dc9c0bc57d\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-19T10:26:14.946082Z","caller":"traceutil/trace.go:171","msg":"trace[1815379373] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-108ce55d-f09b-4415-a6f6-f6dc9c0bc57d; range_end:; response_count:1; response_revision:1892; }","duration":"179.38263ms","start":"2026-09-19T10:26:14.76668Z","end":"2026-09-19T10:26:14.946063Z","steps":["trace[1815379373] 'agreement among raft nodes before linearized reading' (duration: 179.159147ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:15.060379Z","caller":"traceutil/trace.go:171","msg":"trace[2123785546] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"109.023574ms","start":"2026-09-19T10:26:14.951331Z","end":"2026-09-19T10:26:15.060354Z","steps":["trace[2123785546] 'process raft request' (duration: 108.872763ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:15.067519Z","caller":"traceutil/trace.go:171","msg":"trace[11925724] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"115.64064ms","start":"2026-09-19T10:26:14.951858Z","end":"2026-09-19T10:26:15.067499Z","steps":["trace[11925724] 'process raft request' (duration: 115.389977ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:27.016517Z","caller":"traceutil/trace.go:171","msg":"trace[285229170] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"134.928105ms","start":"2026-09-19T10:26:26.881569Z","end":"2026-09-19T10:26:27.016497Z","steps":["trace[285229170] 'process raft request' (duration: 134.788973ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:49.436956Z","caller":"traceutil/trace.go:171","msg":"trace[330028365] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2215; }","duration":"179.79948ms","start":"2026-09-19T10:26:49.257128Z","end":"2026-09-19T10:26:49.436928Z","steps":["trace[330028365] 'read index received' (duration: 123.806446ms)","trace[330028365] 'applied index is now lower than readState.Index' (duration: 55.991834ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:26:49.437117Z","caller":"traceutil/trace.go:171","msg":"trace[1699690425] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"282.776062ms","start":"2026-09-19T10:26:49.154332Z","end":"2026-09-19T10:26:49.437108Z","steps":["trace[1699690425] 'process raft request' (duration: 226.626198ms)","trace[1699690425] 'compare' (duration: 55.7405ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:26:49.437343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.438211ms","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":"warn","ts":"2026-09-19T10:26:49.437384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.256184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-19T10:26:49.437448Z","caller":"traceutil/trace.go:171","msg":"trace[769824058] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2132; }","duration":"180.384005ms","start":"2026-09-19T10:26:49.257049Z","end":"2026-09-19T10:26:49.437433Z","steps":["trace[769824058] 'agreement among raft nodes before linearized reading' (duration: 180.208544ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:49.437448Z","caller":"traceutil/trace.go:171","msg":"trace[454596193] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2132; }","duration":"112.573402ms","start":"2026-09-19T10:26:49.324851Z","end":"2026-09-19T10:26:49.437425Z","steps":["trace[454596193] 'agreement among raft nodes before linearized reading' (duration: 112.30057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:26:49.437573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.21118ms","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-19T10:26:49.437711Z","caller":"traceutil/trace.go:171","msg":"trace[233529437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2132; }","duration":"109.472824ms","start":"2026-09-19T10:26:49.328179Z","end":"2026-09-19T10:26:49.437652Z","steps":["trace[233529437] 'agreement among raft nodes before linearized reading' (duration: 109.14702ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:50.153891Z","caller":"traceutil/trace.go:171","msg":"trace[1382495911] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"137.992487ms","start":"2026-09-19T10:26:50.015861Z","end":"2026-09-19T10:26:50.153853Z","steps":["trace[1382495911] 'process raft request' (duration: 133.299994ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:27:59.791332Z","caller":"traceutil/trace.go:171","msg":"trace[753666276] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"116.309353ms","start":"2026-09-19T10:27:59.675001Z","end":"2026-09-19T10:27:59.791311Z","steps":["trace[753666276] 'process raft request' (duration: 116.20362ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:28:36.075114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.292842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:28:36.075648Z","caller":"traceutil/trace.go:171","msg":"trace[1382256472] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"124.053923ms","start":"2026-09-19T10:28:35.951559Z","end":"2026-09-19T10:28:36.075613Z","steps":["trace[1382256472] 'process raft request' (duration: 123.697904ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:28:36.075749Z","caller":"traceutil/trace.go:171","msg":"trace[119765243] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"185.201502ms","start":"2026-09-19T10:28:35.89047Z","end":"2026-09-19T10:28:36.075671Z","steps":["trace[119765243] 'process raft request' (duration: 28.254844ms)","trace[119765243] 'compare' (duration: 156.105298ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:33:03.621939Z","caller":"traceutil/trace.go:171","msg":"trace[1154736068] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"107.659642ms","start":"2026-09-19T10:33:03.514264Z","end":"2026-09-19T10:33:03.621924Z","steps":["trace[1154736068] 'process raft request' (duration: 107.42247ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:33:08.882468Z","caller":"traceutil/trace.go:171","msg":"trace[392322693] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"188.930026ms","start":"2026-09-19T10:33:08.693515Z","end":"2026-09-19T10:33:08.882445Z","steps":["trace[392322693] 'process raft request' (duration: 188.830575ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:33:08.882685Z","caller":"traceutil/trace.go:171","msg":"trace[988432762] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"191.189083ms","start":"2026-09-19T10:33:08.691453Z","end":"2026-09-19T10:33:08.882642Z","steps":["trace[988432762] 'process raft request' (duration: 146.028677ms)","trace[988432762] 'compare' (duration: 44.587309ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:33:21.397577Z","caller":"traceutil/trace.go:171","msg":"trace[1359911701] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"208.401556ms","start":"2026-09-19T10:33:21.189155Z","end":"2026-09-19T10:33:21.397556Z","steps":["trace[1359911701] 'process raft request' (duration: 208.268614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:33:21.733613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.05714ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-19T10:33:21.734086Z","caller":"traceutil/trace.go:171","msg":"trace[222794674] linearizableReadLoop","detail":"{readStateIndex:5370; appliedIndex:5367; }","duration":"344.265297ms","start":"2026-09-19T10:33:21.389802Z","end":"2026-09-19T10:33:21.734067Z","steps":["trace[222794674] 'read index received' (duration: 7.627612ms)","trace[222794674] 'applied index is now lower than readState.Index' (duration: 336.636715ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:33:21.734078Z","caller":"traceutil/trace.go:171","msg":"trace[1230567149] transaction","detail":"{read_only:false; response_revision:5170; number_of_response:1; }","duration":"352.317815ms","start":"2026-09-19T10:33:21.381733Z","end":"2026-09-19T10:33:21.734051Z","steps":["trace[1230567149] 'process raft request' (duration: 351.998531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:33:21.734207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.381688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:33:21.734296Z","caller":"traceutil/trace.go:171","msg":"trace[47853840] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5171; }","duration":"344.484819ms","start":"2026-09-19T10:33:21.389794Z","end":"2026-09-19T10:33:21.734279Z","steps":["trace[47853840] 'agreement among raft nodes before linearized reading' (duration: 344.324837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:33:21.734334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:33:21.389734Z","time spent":"344.590411ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-09-19T10:33:21.734374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:33:21.381712Z","time spent":"352.442587ms","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:33:21.734733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.326796ms","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-19T10:33:21.734851Z","caller":"traceutil/trace.go:171","msg":"trace[979712494] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5171; }","duration":"172.472617ms","start":"2026-09-19T10:33:21.562335Z","end":"2026-09-19T10:33:21.734807Z","steps":["trace[979712494] 'agreement among raft nodes before linearized reading' (duration: 171.900501ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:33:21.980526Z","caller":"traceutil/trace.go:171","msg":"trace[992450452] linearizableReadLoop","detail":"{readStateIndex:5373; appliedIndex:5372; }","duration":"209.829233ms","start":"2026-09-19T10:33:21.770669Z","end":"2026-09-19T10:33:21.980499Z","steps":["trace[992450452] 'read index received' (duration: 133.144629ms)","trace[992450452] 'applied index is now lower than readState.Index' (duration: 76.683604ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:33:21.980887Z","caller":"traceutil/trace.go:171","msg":"trace[766791352] transaction","detail":"{read_only:false; response_revision:5173; number_of_response:1; }","duration":"241.456497ms","start":"2026-09-19T10:33:21.739413Z","end":"2026-09-19T10:33:21.980869Z","steps":["trace[766791352] 'process raft request' (duration: 164.501241ms)","trace[766791352] 'compare' (duration: 76.44709ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:33:21.981178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.49235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-5nq6z\" ","response":"range_response_count:1 size:9938"} {"level":"info","ts":"2026-09-19T10:33:21.981219Z","caller":"traceutil/trace.go:171","msg":"trace[1202656063] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-5nq6z; range_end:; response_count:1; response_revision:5173; }","duration":"210.578551ms","start":"2026-09-19T10:33:21.770628Z","end":"2026-09-19T10:33:21.981206Z","steps":["trace[1202656063] 'agreement among raft nodes before linearized reading' (duration: 210.426869ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:33:21.981313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.749894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-19T10:33:21.981364Z","caller":"traceutil/trace.go:171","msg":"trace[1360344481] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5173; }","duration":"164.843475ms","start":"2026-09-19T10:33:21.816506Z","end":"2026-09-19T10:33:21.981349Z","steps":["trace[1360344481] 'agreement among raft nodes before linearized reading' (duration: 164.712514ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:33:35.203045Z","caller":"traceutil/trace.go:171","msg":"trace[1837619365] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"115.751722ms","start":"2026-09-19T10:33:35.087271Z","end":"2026-09-19T10:33:35.203023Z","steps":["trace[1837619365] 'process raft request' (duration: 115.57647ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:33:51.940353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2896} {"level":"info","ts":"2026-09-19T10:33:52.049947Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2896,"took":"108.397828ms","hash":700585202,"current-db-size-bytes":52465664,"current-db-size":"52 MB","current-db-size-in-use-bytes":28454912,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T10:33:52.050002Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":700585202,"revision":2896,"compact-revision":-1} {"level":"info","ts":"2026-09-19T10:38:51.946512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5471} {"level":"info","ts":"2026-09-19T10:38:52.053442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5471,"took":"105.326765ms","hash":4204919034,"current-db-size-bytes":52465664,"current-db-size":"52 MB","current-db-size-in-use-bytes":18759680,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-19T10:38:52.053493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4204919034,"revision":5471,"compact-revision":2896}