{"level":"warn","ts":"2026-09-15T22:20:00.408263Z","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-15T22:20:00.408378Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.234: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.234:2380","--initial-cluster=instance=https://162.253.55.234:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.234:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.234: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-15T22:20:00.408502Z","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-15T22:20:00.408519Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.234:2380"]} {"level":"info","ts":"2026-09-15T22:20:00.408545Z","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-15T22:20:00.409225Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.234:2379"]} {"level":"info","ts":"2026-09-15T22:20:00.409306Z","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.234:2380"],"listen-peer-urls":["https://162.253.55.234:2380"],"advertise-client-urls":["https://162.253.55.234:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.234:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.234: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-15T22:20:00.41477Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.250712ms"} {"level":"info","ts":"2026-09-15T22:20:00.42464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"403fac606ddb6bbc","cluster-id":"3f7d75ded657ac1e"} {"level":"info","ts":"2026-09-15T22:20:00.424738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:20:00.424779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc became follower at term 0"} {"level":"info","ts":"2026-09-15T22:20:00.424793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 403fac606ddb6bbc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:20:00.424805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc became follower at term 1"} {"level":"info","ts":"2026-09-15T22:20:00.424851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc switched to configuration voters=(4629608472120093628)"} {"level":"warn","ts":"2026-09-15T22:20:00.440706Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:20:00.443094Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:20:00.445442Z","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-15T22:20:00.450319Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"403fac606ddb6bbc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:20:00.45583Z","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-15T22:20:00.45606Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.234:2380"} {"level":"info","ts":"2026-09-15T22:20:00.456525Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.234:2380"} {"level":"info","ts":"2026-09-15T22:20:00.457446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc switched to configuration voters=(4629608472120093628)"} {"level":"info","ts":"2026-09-15T22:20:00.457523Z","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-15T22:20:00.457569Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f7d75ded657ac1e","local-member-id":"403fac606ddb6bbc","added-peer-id":"403fac606ddb6bbc","added-peer-peer-urls":["https://162.253.55.234:2380"]} {"level":"info","ts":"2026-09-15T22:20:00.457584Z","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-15T22:20:00.457596Z","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-15T22:20:00.456105Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"403fac606ddb6bbc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:20:00.457885Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"403fac606ddb6bbc","initial-advertise-peer-urls":["https://162.253.55.234:2380"],"listen-peer-urls":["https://162.253.55.234:2380"],"advertise-client-urls":["https://162.253.55.234:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.234:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:20:00.457918Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:20:00.527774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:20:00.527846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:20:00.527865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc received MsgPreVoteResp from 403fac606ddb6bbc at term 1"} {"level":"info","ts":"2026-09-15T22:20:00.527881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:20:00.527889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc received MsgVoteResp from 403fac606ddb6bbc at term 2"} {"level":"info","ts":"2026-09-15T22:20:00.5279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"403fac606ddb6bbc became leader at term 2"} {"level":"info","ts":"2026-09-15T22:20:00.527909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 403fac606ddb6bbc elected leader 403fac606ddb6bbc at term 2"} {"level":"info","ts":"2026-09-15T22:20:00.530377Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"403fac606ddb6bbc","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.234:2379]}","request-path":"/0/members/403fac606ddb6bbc/attributes","cluster-id":"3f7d75ded657ac1e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:20:00.53062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:20:00.530709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:20:00.530858Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:20:00.530872Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:20:00.530949Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:20:00.531938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f7d75ded657ac1e","local-member-id":"403fac606ddb6bbc","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:20:00.53204Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:20:00.532074Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:20:00.533071Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.234:2379"} {"level":"info","ts":"2026-09-15T22:20:00.533296Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:20:07.477194Z","caller":"traceutil/trace.go:171","msg":"trace[570110062] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"108.706952ms","start":"2026-09-15T22:20:07.368466Z","end":"2026-09-15T22:20:07.477173Z","steps":["trace[570110062] 'process raft request' (duration: 108.146508ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:07.47737Z","caller":"traceutil/trace.go:171","msg":"trace[797226580] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"108.605509ms","start":"2026-09-15T22:20:07.368757Z","end":"2026-09-15T22:20:07.477362Z","steps":["trace[797226580] 'process raft request' (duration: 107.944522ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:07.477609Z","caller":"traceutil/trace.go:171","msg":"trace[108138928] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"108.769783ms","start":"2026-09-15T22:20:07.368828Z","end":"2026-09-15T22:20:07.477597Z","steps":["trace[108138928] 'process raft request' (duration: 107.896602ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:07.477704Z","caller":"traceutil/trace.go:171","msg":"trace[1626357791] transaction","detail":"{read_only:false; number_of_response:0; response_revision:318; }","duration":"108.449235ms","start":"2026-09-15T22:20:07.369222Z","end":"2026-09-15T22:20:07.477672Z","steps":["trace[1626357791] 'process raft request' (duration: 107.520162ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:09.844731Z","caller":"traceutil/trace.go:171","msg":"trace[664971148] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"107.533899ms","start":"2026-09-15T22:20:09.737177Z","end":"2026-09-15T22:20:09.844711Z","steps":["trace[664971148] 'process raft request' (duration: 107.479267ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:09.845034Z","caller":"traceutil/trace.go:171","msg":"trace[2051441737] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"111.388806ms","start":"2026-09-15T22:20:09.733598Z","end":"2026-09-15T22:20:09.844986Z","steps":["trace[2051441737] 'process raft request' (duration: 70.454312ms)","trace[2051441737] 'compare' (duration: 40.210415ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:20:09.846342Z","caller":"traceutil/trace.go:171","msg":"trace[446964204] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"108.130044ms","start":"2026-09-15T22:20:09.738185Z","end":"2026-09-15T22:20:09.846315Z","steps":["trace[446964204] 'process raft request' (duration: 107.97851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:29.453138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.023592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T22:20:29.453235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.813453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-09-15T22:20:29.453351Z","caller":"traceutil/trace.go:171","msg":"trace[789988777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"184.995566ms","start":"2026-09-15T22:20:29.268335Z","end":"2026-09-15T22:20:29.453331Z","steps":["trace[789988777] 'range keys from in-memory index tree' (duration: 184.633418ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:29.453285Z","caller":"traceutil/trace.go:171","msg":"trace[712471084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"150.240045ms","start":"2026-09-15T22:20:29.303023Z","end":"2026-09-15T22:20:29.453263Z","steps":["trace[712471084] 'range keys from in-memory index tree' (duration: 149.925129ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:52.531071Z","caller":"traceutil/trace.go:171","msg":"trace[559686923] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"309.265306ms","start":"2026-09-15T22:20:52.221745Z","end":"2026-09-15T22:20:52.53101Z","steps":["trace[559686923] 'read index received' (duration: 307.489352ms)","trace[559686923] 'applied index is now lower than readState.Index' (duration: 1.774654ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:20:52.53114Z","caller":"traceutil/trace.go:171","msg":"trace[1387584505] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"310.389368ms","start":"2026-09-15T22:20:52.220704Z","end":"2026-09-15T22:20:52.531094Z","steps":["trace[1387584505] 'process raft request' (duration: 308.540573ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:52.53175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.909428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-09-15T22:20:52.531798Z","caller":"traceutil/trace.go:171","msg":"trace[423907358] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:656; }","duration":"310.06592ms","start":"2026-09-15T22:20:52.221717Z","end":"2026-09-15T22:20:52.531783Z","steps":["trace[423907358] 'agreement among raft nodes before linearized reading' (duration: 309.462739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:20:52.531867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:20:52.221704Z","time spent":"310.151743ms","remote":"127.0.0.1:55256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-09-15T22:20:52.532192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:20:52.220688Z","time spent":"310.750564ms","remote":"127.0.0.1:55256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:20:52.548151Z","caller":"traceutil/trace.go:171","msg":"trace[62003174] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"172.589415ms","start":"2026-09-15T22:20:52.375542Z","end":"2026-09-15T22:20:52.548131Z","steps":["trace[62003174] 'process raft request' (duration: 172.541764ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:52.548414Z","caller":"traceutil/trace.go:171","msg":"trace[1754187326] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"244.251383ms","start":"2026-09-15T22:20:52.304151Z","end":"2026-09-15T22:20:52.548403Z","steps":["trace[1754187326] 'process raft request' (duration: 243.466509ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:52.870108Z","caller":"traceutil/trace.go:171","msg":"trace[1289289851] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"132.565043ms","start":"2026-09-15T22:20:52.737523Z","end":"2026-09-15T22:20:52.870088Z","steps":["trace[1289289851] 'read index received' (duration: 113.114137ms)","trace[1289289851] 'applied index is now lower than readState.Index' (duration: 19.449846ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:20:52.870394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.844778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-09-15T22:20:52.870422Z","caller":"traceutil/trace.go:171","msg":"trace[224573184] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:665; }","duration":"132.90952ms","start":"2026-09-15T22:20:52.737503Z","end":"2026-09-15T22:20:52.870413Z","steps":["trace[224573184] 'agreement among raft nodes before linearized reading' (duration: 132.657285ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:20:52.870404Z","caller":"traceutil/trace.go:171","msg":"trace[463572444] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"134.677193ms","start":"2026-09-15T22:20:52.735539Z","end":"2026-09-15T22:20:52.870216Z","steps":["trace[463572444] 'process raft request' (duration: 115.089334ms)","trace[463572444] 'compare' (duration: 19.345695ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:21:00.694249Z","caller":"traceutil/trace.go:171","msg":"trace[1136031501] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"169.600521ms","start":"2026-09-15T22:21:00.524625Z","end":"2026-09-15T22:21:00.694225Z","steps":["trace[1136031501] 'process raft request' (duration: 169.471718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:21:01.339949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.077631ms","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-15T22:21:01.340034Z","caller":"traceutil/trace.go:171","msg":"trace[79637720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"177.214933ms","start":"2026-09-15T22:21:01.162799Z","end":"2026-09-15T22:21:01.340014Z","steps":["trace[79637720] 'range keys from in-memory index tree' (duration: 176.902508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:21:18.591019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.130763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:21:18.591118Z","caller":"traceutil/trace.go:171","msg":"trace[374423629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"287.288495ms","start":"2026-09-15T22:21:18.303807Z","end":"2026-09-15T22:21:18.591096Z","steps":["trace[374423629] 'range keys from in-memory index tree' (duration: 287.020061ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:21:54.512781Z","caller":"traceutil/trace.go:171","msg":"trace[1174285619] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"114.220413ms","start":"2026-09-15T22:21:54.398534Z","end":"2026-09-15T22:21:54.512754Z","steps":["trace[1174285619] 'read index received' (duration: 114.114271ms)","trace[1174285619] 'applied index is now lower than readState.Index' (duration: 104.982µs)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:21:54.513141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.577348ms","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-15T22:21:54.51325Z","caller":"traceutil/trace.go:171","msg":"trace[1432139062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"114.75037ms","start":"2026-09-15T22:21:54.398475Z","end":"2026-09-15T22:21:54.513225Z","steps":["trace[1432139062] 'agreement among raft nodes before linearized reading' (duration: 114.454516ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:10.102728Z","caller":"traceutil/trace.go:171","msg":"trace[1203913815] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"121.997893ms","start":"2026-09-15T22:22:09.980688Z","end":"2026-09-15T22:22:10.102686Z","steps":["trace[1203913815] 'process raft request' (duration: 120.649063ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:43.905755Z","caller":"traceutil/trace.go:171","msg":"trace[1763385025] linearizableReadLoop","detail":"{readStateIndex:1997; appliedIndex:1996; }","duration":"178.324551ms","start":"2026-09-15T22:22:43.727391Z","end":"2026-09-15T22:22:43.905716Z","steps":["trace[1763385025] 'read index received' (duration: 178.088147ms)","trace[1763385025] 'applied index is now lower than readState.Index' (duration: 235.104µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:22:43.905823Z","caller":"traceutil/trace.go:171","msg":"trace[1091028907] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"196.215593ms","start":"2026-09-15T22:22:43.709548Z","end":"2026-09-15T22:22:43.905764Z","steps":["trace[1091028907] 'process raft request' (duration: 195.99209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:43.906172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.749417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-32b9973e-fc05-4f83-99f5-ab56e4158089\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-15T22:22:43.90625Z","caller":"traceutil/trace.go:171","msg":"trace[1776854066] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-32b9973e-fc05-4f83-99f5-ab56e4158089; range_end:; response_count:1; response_revision:1936; }","duration":"178.885559ms","start":"2026-09-15T22:22:43.727346Z","end":"2026-09-15T22:22:43.906232Z","steps":["trace[1776854066] 'agreement among raft nodes before linearized reading' (duration: 178.572515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:44.107052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.114885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:22:44.107398Z","caller":"traceutil/trace.go:171","msg":"trace[138446465] transaction","detail":"{read_only:false; response_revision:1937; number_of_response:1; }","duration":"197.029762ms","start":"2026-09-15T22:22:43.910304Z","end":"2026-09-15T22:22:44.107334Z","steps":["trace[138446465] 'process raft request' (duration: 95.176506ms)","trace[138446465] 'compare' (duration: 100.764941ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:22:44.107521Z","caller":"traceutil/trace.go:171","msg":"trace[719556007] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1997; }","duration":"105.355052ms","start":"2026-09-15T22:22:44.002146Z","end":"2026-09-15T22:22:44.107501Z","steps":["trace[719556007] 'read index received' (duration: 3.354865ms)","trace[719556007] 'applied index is now lower than readState.Index' (duration: 101.999107ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:22:44.108114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.94148ms","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-15T22:22:44.108181Z","caller":"traceutil/trace.go:171","msg":"trace[1571435864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1939; }","duration":"106.069762ms","start":"2026-09-15T22:22:44.002098Z","end":"2026-09-15T22:22:44.108168Z","steps":["trace[1571435864] 'agreement among raft nodes before linearized reading' (duration: 105.796749ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:44.108354Z","caller":"traceutil/trace.go:171","msg":"trace[1840595095] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"194.039871ms","start":"2026-09-15T22:22:43.914302Z","end":"2026-09-15T22:22:44.108342Z","steps":["trace[1840595095] 'process raft request' (duration: 192.860095ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:44.108522Z","caller":"traceutil/trace.go:171","msg":"trace[1436241079] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"154.667819ms","start":"2026-09-15T22:22:43.953845Z","end":"2026-09-15T22:22:44.108512Z","steps":["trace[1436241079] 'process raft request' (duration: 153.573034ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:44.233475Z","caller":"traceutil/trace.go:171","msg":"trace[453840341] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"116.115028ms","start":"2026-09-15T22:22:44.117297Z","end":"2026-09-15T22:22:44.233412Z","steps":["trace[453840341] 'process raft request' (duration: 116.042697ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:44.233603Z","caller":"traceutil/trace.go:171","msg":"trace[951676912] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"117.524037ms","start":"2026-09-15T22:22:44.11605Z","end":"2026-09-15T22:22:44.233574Z","steps":["trace[951676912] 'process raft request' (duration: 116.252579ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:50.409522Z","caller":"traceutil/trace.go:171","msg":"trace[1926511573] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"173.524205ms","start":"2026-09-15T22:22:50.235954Z","end":"2026-09-15T22:22:50.409478Z","steps":["trace[1926511573] 'process raft request' (duration: 93.449943ms)","trace[1926511573] 'compare' (duration: 79.757618ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:22:50.409561Z","caller":"traceutil/trace.go:171","msg":"trace[2047834640] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2086; }","duration":"173.353983ms","start":"2026-09-15T22:22:50.236183Z","end":"2026-09-15T22:22:50.409537Z","steps":["trace[2047834640] 'read index received' (duration: 93.175819ms)","trace[2047834640] 'applied index is now lower than readState.Index' (duration: 80.177224ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:22:50.410167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.959792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-32b9973e-fc05-4f83-99f5-ab56e4158089\" ","response":"range_response_count:1 size:1303"} {"level":"info","ts":"2026-09-15T22:22:50.410251Z","caller":"traceutil/trace.go:171","msg":"trace[144606442] range","detail":"{range_begin:/registry/persistentvolumes/pvc-32b9973e-fc05-4f83-99f5-ab56e4158089; range_end:; response_count:1; response_revision:2023; }","duration":"174.090143ms","start":"2026-09-15T22:22:50.23614Z","end":"2026-09-15T22:22:50.41023Z","steps":["trace[144606442] 'agreement among raft nodes before linearized reading' (duration: 173.526026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:50.410316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.719307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T22:22:50.410323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.327672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:22:50.410399Z","caller":"traceutil/trace.go:171","msg":"trace[749066475] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2023; }","duration":"108.423974ms","start":"2026-09-15T22:22:50.301937Z","end":"2026-09-15T22:22:50.410361Z","steps":["trace[749066475] 'agreement among raft nodes before linearized reading' (duration: 108.313613ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:22:50.41043Z","caller":"traceutil/trace.go:171","msg":"trace[2086381066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2023; }","duration":"105.836029ms","start":"2026-09-15T22:22:50.304518Z","end":"2026-09-15T22:22:50.410354Z","steps":["trace[2086381066] 'agreement among raft nodes before linearized reading' (duration: 105.711276ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:22:50.410784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.876329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-09-15T22:22:50.410832Z","caller":"traceutil/trace.go:171","msg":"trace[1548787527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2023; }","duration":"155.964521ms","start":"2026-09-15T22:22:50.254855Z","end":"2026-09-15T22:22:50.410819Z","steps":["trace[1548787527] 'agreement among raft nodes before linearized reading' (duration: 155.774769ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:21.810568Z","caller":"traceutil/trace.go:171","msg":"trace[1180962021] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"168.259012ms","start":"2026-09-15T22:23:21.64228Z","end":"2026-09-15T22:23:21.810539Z","steps":["trace[1180962021] 'process raft request' (duration: 168.082298ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:21.998701Z","caller":"traceutil/trace.go:171","msg":"trace[1290820924] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"179.818371ms","start":"2026-09-15T22:23:21.818844Z","end":"2026-09-15T22:23:21.998662Z","steps":["trace[1290820924] 'process raft request' (duration: 161.972219ms)","trace[1290820924] 'compare' (duration: 17.63162ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:23:22.167283Z","caller":"traceutil/trace.go:171","msg":"trace[2044691313] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"156.164822ms","start":"2026-09-15T22:23:22.011087Z","end":"2026-09-15T22:23:22.167252Z","steps":["trace[2044691313] 'process raft request' (duration: 146.01615ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:23:22.354323Z","caller":"traceutil/trace.go:171","msg":"trace[69895346] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"184.057874ms","start":"2026-09-15T22:23:22.170229Z","end":"2026-09-15T22:23:22.354287Z","steps":["trace[69895346] 'process raft request' (duration: 135.883237ms)","trace[69895346] 'compare' (duration: 47.992745ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:01.502847Z","caller":"traceutil/trace.go:171","msg":"trace[1269268810] linearizableReadLoop","detail":"{readStateIndex:2670; appliedIndex:2669; }","duration":"101.188926ms","start":"2026-09-15T22:24:01.401619Z","end":"2026-09-15T22:24:01.502808Z","steps":["trace[1269268810] 'read index received' (duration: 99.125472ms)","trace[1269268810] 'applied index is now lower than readState.Index' (duration: 2.061614ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:01.502894Z","caller":"traceutil/trace.go:171","msg":"trace[1723253984] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"101.637811ms","start":"2026-09-15T22:24:01.401218Z","end":"2026-09-15T22:24:01.502856Z","steps":["trace[1723253984] 'process raft request' (duration: 99.447424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:24:01.503101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.453396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:24:01.503189Z","caller":"traceutil/trace.go:171","msg":"trace[906751613] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2573; }","duration":"101.584571ms","start":"2026-09-15T22:24:01.401582Z","end":"2026-09-15T22:24:01.503167Z","steps":["trace[906751613] 'agreement among raft nodes before linearized reading' (duration: 101.359882ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:13.373636Z","caller":"traceutil/trace.go:171","msg":"trace[1231763879] transaction","detail":"{read_only:false; response_revision:2699; number_of_response:1; }","duration":"102.994249ms","start":"2026-09-15T22:24:13.270619Z","end":"2026-09-15T22:24:13.373613Z","steps":["trace[1231763879] 'process raft request' (duration: 102.492422ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:47.939835Z","caller":"traceutil/trace.go:171","msg":"trace[941063947] transaction","detail":"{read_only:false; response_revision:2938; number_of_response:1; }","duration":"109.232356ms","start":"2026-09-15T22:24:47.830532Z","end":"2026-09-15T22:24:47.939764Z","steps":["trace[941063947] 'process raft request' (duration: 108.928747ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:24:48.060011Z","caller":"traceutil/trace.go:171","msg":"trace[1445325451] transaction","detail":"{read_only:false; response_revision:2939; number_of_response:1; }","duration":"110.319235ms","start":"2026-09-15T22:24:47.949187Z","end":"2026-09-15T22:24:48.059506Z","steps":["trace[1445325451] 'process raft request' (duration: 87.700386ms)","trace[1445325451] 'compare' (duration: 22.475003ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:24:48.324754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.537657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:24:48.324898Z","caller":"traceutil/trace.go:171","msg":"trace[1308053097] linearizableReadLoop","detail":"{readStateIndex:3056; appliedIndex:3055; }","duration":"225.111235ms","start":"2026-09-15T22:24:48.099762Z","end":"2026-09-15T22:24:48.324873Z","steps":["trace[1308053097] 'read index received' (duration: 83.358432ms)","trace[1308053097] 'applied index is now lower than readState.Index' (duration: 141.751203ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:24:48.325054Z","caller":"traceutil/trace.go:171","msg":"trace[1765572116] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"256.13805ms","start":"2026-09-15T22:24:48.068867Z","end":"2026-09-15T22:24:48.325005Z","steps":["trace[1765572116] 'process raft request' (duration: 114.217752ms)","trace[1765572116] 'compare' (duration: 141.32828ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:24:48.325496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.733183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-09-15T22:24:48.325592Z","caller":"traceutil/trace.go:171","msg":"trace[1588058764] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2940; }","duration":"225.903948ms","start":"2026-09-15T22:24:48.099671Z","end":"2026-09-15T22:24:48.325575Z","steps":["trace[1588058764] 'agreement among raft nodes before linearized reading' (duration: 225.64053ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:22.902819Z","caller":"traceutil/trace.go:171","msg":"trace[2031118922] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"106.197514ms","start":"2026-09-15T22:27:22.796571Z","end":"2026-09-15T22:27:22.902768Z","steps":["trace[2031118922] 'process raft request' (duration: 105.877397ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:23.074018Z","caller":"traceutil/trace.go:171","msg":"trace[1678389069] linearizableReadLoop","detail":"{readStateIndex:4854; appliedIndex:4853; }","duration":"100.98244ms","start":"2026-09-15T22:27:22.973001Z","end":"2026-09-15T22:27:23.073983Z","steps":["trace[1678389069] 'read index received' (duration: 78.620686ms)","trace[1678389069] 'applied index is now lower than readState.Index' (duration: 22.360564ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:27:23.074416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.390548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-15T22:27:23.074446Z","caller":"traceutil/trace.go:171","msg":"trace[1169119742] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4650; }","duration":"101.450189ms","start":"2026-09-15T22:27:22.972986Z","end":"2026-09-15T22:27:23.074437Z","steps":["trace[1169119742] 'agreement among raft nodes before linearized reading' (duration: 101.119282ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:23.080094Z","caller":"traceutil/trace.go:171","msg":"trace[952482700] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"103.55891ms","start":"2026-09-15T22:27:22.970972Z","end":"2026-09-15T22:27:23.074531Z","steps":["trace[952482700] 'process raft request' (duration: 80.656135ms)","trace[952482700] 'compare' (duration: 22.13578ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:30.946392Z","caller":"traceutil/trace.go:171","msg":"trace[714629033] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"257.20252ms","start":"2026-09-15T22:27:30.689155Z","end":"2026-09-15T22:27:30.946357Z","steps":["trace[714629033] 'process raft request' (duration: 256.963985ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:31.075017Z","caller":"traceutil/trace.go:171","msg":"trace[982607322] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"384.998889ms","start":"2026-09-15T22:27:30.68999Z","end":"2026-09-15T22:27:31.074989Z","steps":["trace[982607322] 'process raft request' (duration: 384.902527ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:31.075061Z","caller":"traceutil/trace.go:171","msg":"trace[290927519] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"385.453237ms","start":"2026-09-15T22:27:30.689549Z","end":"2026-09-15T22:27:31.075002Z","steps":["trace[290927519] 'process raft request' (duration: 368.965982ms)","trace[290927519] 'compare' (duration: 16.21073ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:27:31.075242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:30.689984Z","time spent":"385.129901ms","remote":"127.0.0.1:55362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:27:31.075306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:30.689541Z","time spent":"385.62661ms","remote":"127.0.0.1:55482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:27:31.075815Z","caller":"traceutil/trace.go:171","msg":"trace[1449510850] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4953; }","duration":"362.087859ms","start":"2026-09-15T22:27:30.713713Z","end":"2026-09-15T22:27:31.075801Z","steps":["trace[1449510850] 'read index received' (duration: 232.65525ms)","trace[1449510850] 'applied index is now lower than readState.Index' (duration: 129.430479ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:31.075809Z","caller":"traceutil/trace.go:171","msg":"trace[1491775550] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"384.088191ms","start":"2026-09-15T22:27:30.691684Z","end":"2026-09-15T22:27:31.075772Z","steps":["trace[1491775550] 'process raft request' (duration: 383.264245ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:31.075981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:30.691679Z","time spent":"384.205303ms","remote":"127.0.0.1:55362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1282,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:27:31.076005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.719656ms","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":"warn","ts":"2026-09-15T22:27:31.076015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.300883ms","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-15T22:27:31.076045Z","caller":"traceutil/trace.go:171","msg":"trace[763892972] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4747; }","duration":"116.837958ms","start":"2026-09-15T22:27:30.959197Z","end":"2026-09-15T22:27:31.076035Z","steps":["trace[763892972] 'agreement among raft nodes before linearized reading' (duration: 116.742706ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:31.076061Z","caller":"traceutil/trace.go:171","msg":"trace[769611240] 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:4747; }","duration":"362.345884ms","start":"2026-09-15T22:27:30.7137Z","end":"2026-09-15T22:27:31.076046Z","steps":["trace[769611240] 'agreement among raft nodes before linearized reading' (duration: 362.199501ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:31.076093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:27:30.713672Z","time spent":"362.413075ms","remote":"127.0.0.1:55352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-15T22:27:35.136256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.03507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63167b64-6974-4843-873a-6b2df679cd1c\" ","response":"range_response_count:1 size:3936"} {"level":"info","ts":"2026-09-15T22:27:35.136358Z","caller":"traceutil/trace.go:171","msg":"trace[878959071] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63167b64-6974-4843-873a-6b2df679cd1c; range_end:; response_count:1; response_revision:4848; }","duration":"144.221933ms","start":"2026-09-15T22:27:34.992114Z","end":"2026-09-15T22:27:35.136336Z","steps":["trace[878959071] 'range keys from in-memory index tree' (duration: 143.887326ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:27:43.983157Z","caller":"traceutil/trace.go:171","msg":"trace[79909567] linearizableReadLoop","detail":"{readStateIndex:5202; appliedIndex:5201; }","duration":"138.575203ms","start":"2026-09-15T22:27:43.844548Z","end":"2026-09-15T22:27:43.983123Z","steps":["trace[79909567] 'read index received' (duration: 138.270656ms)","trace[79909567] 'applied index is now lower than readState.Index' (duration: 303.437µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:27:43.98325Z","caller":"traceutil/trace.go:171","msg":"trace[2046342444] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"153.981451ms","start":"2026-09-15T22:27:43.829219Z","end":"2026-09-15T22:27:43.983201Z","steps":["trace[2046342444] 'process raft request' (duration: 153.674335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:27:43.983514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.933629ms","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-15T22:27:43.983589Z","caller":"traceutil/trace.go:171","msg":"trace[960395135] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4985; }","duration":"139.085072ms","start":"2026-09-15T22:27:43.844481Z","end":"2026-09-15T22:27:43.983566Z","steps":["trace[960395135] 'agreement among raft nodes before linearized reading' (duration: 138.838087ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:30:01.096307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3025} {"level":"info","ts":"2026-09-15T22:30:01.202061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3025,"took":"103.082147ms","hash":940562154,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":30707712,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-15T22:30:01.202134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":940562154,"revision":3025,"compact-revision":-1} {"level":"warn","ts":"2026-09-15T22:31:17.388206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.993165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-15T22:31:17.388289Z","caller":"traceutil/trace.go:171","msg":"trace[1067517170] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:6831; }","duration":"104.130926ms","start":"2026-09-15T22:31:17.284139Z","end":"2026-09-15T22:31:17.38827Z","steps":["trace[1067517170] 'range keys from in-memory index tree' (duration: 103.820671ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:31:22.62275Z","caller":"traceutil/trace.go:171","msg":"trace[49975562] linearizableReadLoop","detail":"{readStateIndex:7186; appliedIndex:7185; }","duration":"148.88755ms","start":"2026-09-15T22:31:22.473838Z","end":"2026-09-15T22:31:22.622725Z","steps":["trace[49975562] 'read index received' (duration: 148.689277ms)","trace[49975562] 'applied index is now lower than readState.Index' (duration: 197.453µs)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:31:22.622825Z","caller":"traceutil/trace.go:171","msg":"trace[428596122] transaction","detail":"{read_only:false; response_revision:6872; number_of_response:1; }","duration":"155.195339ms","start":"2026-09-15T22:31:22.467578Z","end":"2026-09-15T22:31:22.622773Z","steps":["trace[428596122] 'process raft request' (duration: 154.996846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:31:22.622968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.111423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-15T22:31:22.623004Z","caller":"traceutil/trace.go:171","msg":"trace[317780830] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:6872; }","duration":"149.220415ms","start":"2026-09-15T22:31:22.473774Z","end":"2026-09-15T22:31:22.622994Z","steps":["trace[317780830] 'agreement among raft nodes before linearized reading' (duration: 149.076043ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:31:37.67686Z","caller":"traceutil/trace.go:171","msg":"trace[1579800852] transaction","detail":"{read_only:false; response_revision:7019; number_of_response:1; }","duration":"108.665937ms","start":"2026-09-15T22:31:37.568173Z","end":"2026-09-15T22:31:37.676839Z","steps":["trace[1579800852] 'process raft request' (duration: 108.457684ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:31:37.987348Z","caller":"traceutil/trace.go:171","msg":"trace[184617672] transaction","detail":"{read_only:false; response_revision:7020; number_of_response:1; }","duration":"304.973202ms","start":"2026-09-15T22:31:37.682351Z","end":"2026-09-15T22:31:37.987324Z","steps":["trace[184617672] 'process raft request' (duration: 304.83488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:31:37.987543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:31:37.682332Z","time spent":"305.138765ms","remote":"127.0.0.1:55476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:31:38.012202Z","caller":"traceutil/trace.go:171","msg":"trace[1419600629] linearizableReadLoop","detail":"{readStateIndex:7343; appliedIndex:7340; }","duration":"304.2128ms","start":"2026-09-15T22:31:37.707971Z","end":"2026-09-15T22:31:38.012184Z","steps":["trace[1419600629] 'read index received' (duration: 279.257848ms)","trace[1419600629] 'applied index is now lower than readState.Index' (duration: 24.954202ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:31:38.012292Z","caller":"traceutil/trace.go:171","msg":"trace[1829178805] transaction","detail":"{read_only:false; number_of_response:0; response_revision:7020; }","duration":"329.569069ms","start":"2026-09-15T22:31:37.682717Z","end":"2026-09-15T22:31:38.012286Z","steps":["trace[1829178805] 'process raft request' (duration: 329.265614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:31:38.012502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.520875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-15T22:31:38.012537Z","caller":"traceutil/trace.go:171","msg":"trace[1293054405] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:7021; }","duration":"304.603516ms","start":"2026-09-15T22:31:37.707925Z","end":"2026-09-15T22:31:38.012529Z","steps":["trace[1293054405] 'agreement among raft nodes before linearized reading' (duration: 304.495735ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:31:38.012559Z","caller":"traceutil/trace.go:171","msg":"trace[109096735] transaction","detail":"{read_only:false; response_revision:7021; number_of_response:1; }","duration":"327.027258ms","start":"2026-09-15T22:31:37.68551Z","end":"2026-09-15T22:31:38.012537Z","steps":["trace[109096735] 'process raft request' (duration: 326.611332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:31:38.012602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:31:37.707895Z","time spent":"304.676718ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-09-15T22:31:38.012676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:31:37.685497Z","time spent":"327.11776ms","remote":"127.0.0.1:55304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:31:38.012718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.712103ms","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-15T22:31:38.012778Z","caller":"traceutil/trace.go:171","msg":"trace[542746798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7021; }","duration":"226.829834ms","start":"2026-09-15T22:31:37.785929Z","end":"2026-09-15T22:31:38.012759Z","steps":["trace[542746798] 'agreement among raft nodes before linearized reading' (duration: 226.629001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:31:38.013081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:31:37.682673Z","time spent":"329.63916ms","remote":"127.0.0.1:55662","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:31:38.01323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.833058ms","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-15T22:31:38.013436Z","caller":"traceutil/trace.go:171","msg":"trace[354569827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7021; }","duration":"232.9206ms","start":"2026-09-15T22:31:37.780358Z","end":"2026-09-15T22:31:38.013279Z","steps":["trace[354569827] 'agreement among raft nodes before linearized reading' (duration: 232.113837ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:35:01.109879Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6145} {"level":"info","ts":"2026-09-15T22:35:01.21254Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6145,"took":"100.701142ms","hash":3671615028,"current-db-size-bytes":58052608,"current-db-size":"58 MB","current-db-size-in-use-bytes":18710528,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-15T22:35:01.212595Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3671615028,"revision":6145,"compact-revision":3025}