{"level":"warn","ts":"2026-09-22T01:48:02.154208Z","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-22T01:48:02.1543Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.179: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.179:2380","--initial-cluster=instance=https://162.253.55.179:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.179:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.179: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-22T01:48:02.154358Z","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-22T01:48:02.154367Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.179:2380"]} {"level":"info","ts":"2026-09-22T01:48:02.154387Z","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-22T01:48:02.154989Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.179:2379"]} {"level":"info","ts":"2026-09-22T01:48:02.155088Z","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.179:2380"],"listen-peer-urls":["https://162.253.55.179:2380"],"advertise-client-urls":["https://162.253.55.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.179: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-22T01:48:02.160185Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.899945ms"} {"level":"info","ts":"2026-09-22T01:48:02.167362Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"68b29b9453c9d66d","cluster-id":"f39bc50358d1a417"} {"level":"info","ts":"2026-09-22T01:48:02.16742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:48:02.167439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became follower at term 0"} {"level":"info","ts":"2026-09-22T01:48:02.167453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 68b29b9453c9d66d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:48:02.16746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became follower at term 1"} {"level":"info","ts":"2026-09-22T01:48:02.167494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d switched to configuration voters=(7544263387162203757)"} {"level":"warn","ts":"2026-09-22T01:48:02.173866Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:48:02.175647Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:48:02.176909Z","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-22T01:48:02.185593Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"68b29b9453c9d66d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:48:02.196228Z","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-22T01:48:02.196393Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"68b29b9453c9d66d","initial-advertise-peer-urls":["https://162.253.55.179:2380"],"listen-peer-urls":["https://162.253.55.179:2380"],"advertise-client-urls":["https://162.253.55.179:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.179:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:48:02.19641Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:48:02.196557Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.179:2380"} {"level":"info","ts":"2026-09-22T01:48:02.196565Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.179:2380"} {"level":"info","ts":"2026-09-22T01:48:02.196755Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"68b29b9453c9d66d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:48:02.196853Z","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-22T01:48:02.196882Z","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-22T01:48:02.196888Z","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-22T01:48:02.197049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d switched to configuration voters=(7544263387162203757)"} {"level":"info","ts":"2026-09-22T01:48:02.197122Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f39bc50358d1a417","local-member-id":"68b29b9453c9d66d","added-peer-id":"68b29b9453c9d66d","added-peer-peer-urls":["https://162.253.55.179:2380"]} {"level":"info","ts":"2026-09-22T01:48:03.069951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:48:03.070033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:48:03.070094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d received MsgPreVoteResp from 68b29b9453c9d66d at term 1"} {"level":"info","ts":"2026-09-22T01:48:03.070131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:48:03.07015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d received MsgVoteResp from 68b29b9453c9d66d at term 2"} {"level":"info","ts":"2026-09-22T01:48:03.070171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68b29b9453c9d66d became leader at term 2"} {"level":"info","ts":"2026-09-22T01:48:03.070192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 68b29b9453c9d66d elected leader 68b29b9453c9d66d at term 2"} {"level":"info","ts":"2026-09-22T01:48:03.073073Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"68b29b9453c9d66d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.179:2379]}","request-path":"/0/members/68b29b9453c9d66d/attributes","cluster-id":"f39bc50358d1a417","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:48:03.073402Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:03.07359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:48:03.074298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:48:03.074506Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:48:03.074533Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:48:03.074581Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f39bc50358d1a417","local-member-id":"68b29b9453c9d66d","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:03.074659Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:03.074685Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:03.076892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.179:2379"} {"level":"info","ts":"2026-09-22T01:48:03.077421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-09-22T01:48:20.598586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.02802ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:48:20.598854Z","caller":"traceutil/trace.go:171","msg":"trace[295275053] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"206.425808ms","start":"2026-09-22T01:48:20.392387Z","end":"2026-09-22T01:48:20.598812Z","steps":["trace[295275053] 'process raft request' (duration: 94.33993ms)","trace[295275053] 'compare' (duration: 110.854517ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:48:40.156247Z","caller":"traceutil/trace.go:171","msg":"trace[1494826273] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"128.965032ms","start":"2026-09-22T01:48:40.027257Z","end":"2026-09-22T01:48:40.156222Z","steps":["trace[1494826273] 'process raft request' (duration: 115.593808ms)","trace[1494826273] 'compare' (duration: 13.270593ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:48:40.169588Z","caller":"traceutil/trace.go:171","msg":"trace[1415170535] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"141.044008ms","start":"2026-09-22T01:48:40.028485Z","end":"2026-09-22T01:48:40.169529Z","steps":["trace[1415170535] 'process raft request' (duration: 140.914986ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.1696Z","caller":"traceutil/trace.go:171","msg":"trace[1308087074] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"142.214825ms","start":"2026-09-22T01:48:40.027355Z","end":"2026-09-22T01:48:40.16957Z","steps":["trace[1308087074] 'process raft request' (duration: 141.87266ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.1699Z","caller":"traceutil/trace.go:171","msg":"trace[1785566909] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"140.281607ms","start":"2026-09-22T01:48:40.029596Z","end":"2026-09-22T01:48:40.169877Z","steps":["trace[1785566909] 'process raft request' (duration: 139.864581ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:42.248944Z","caller":"traceutil/trace.go:171","msg":"trace[9657743] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"120.638529ms","start":"2026-09-22T01:48:42.128283Z","end":"2026-09-22T01:48:42.248922Z","steps":["trace[9657743] 'process raft request' (duration: 107.512001ms)","trace[9657743] 'compare' (duration: 13.017116ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:48:42.249318Z","caller":"traceutil/trace.go:171","msg":"trace[418427052] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"120.975713ms","start":"2026-09-22T01:48:42.128325Z","end":"2026-09-22T01:48:42.249301Z","steps":["trace[418427052] 'process raft request' (duration: 120.76212ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:48.373167Z","caller":"traceutil/trace.go:171","msg":"trace[88350089] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"318.422065ms","start":"2026-09-22T01:48:48.05472Z","end":"2026-09-22T01:48:48.373143Z","steps":["trace[88350089] 'process raft request' (duration: 318.225993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:48.373317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:48.054674Z","time spent":"318.571678ms","remote":"127.0.0.1:45998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:48:48.886584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.516597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:48:48.886672Z","caller":"traceutil/trace.go:171","msg":"trace[786349300] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"674.013504ms","start":"2026-09-22T01:48:48.212641Z","end":"2026-09-22T01:48:48.886654Z","steps":["trace[786349300] 'read index received' (duration: 160.569746ms)","trace[786349300] 'applied index is now lower than readState.Index' (duration: 513.442598ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:48:48.88674Z","caller":"traceutil/trace.go:171","msg":"trace[4403785] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"735.065127ms","start":"2026-09-22T01:48:48.151627Z","end":"2026-09-22T01:48:48.886692Z","steps":["trace[4403785] 'process raft request' (duration: 282.369867ms)","trace[4403785] 'compare' (duration: 451.751877ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:48:48.886789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.153086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:48:48.886816Z","caller":"traceutil/trace.go:171","msg":"trace[709002782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"674.205787ms","start":"2026-09-22T01:48:48.212601Z","end":"2026-09-22T01:48:48.886807Z","steps":["trace[709002782] 'agreement among raft nodes before linearized reading' (duration: 674.155607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:48.886844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:48.21259Z","time spent":"674.246918ms","remote":"127.0.0.1:45808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-22T01:48:48.886911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:48.151598Z","time spent":"735.2181ms","remote":"127.0.0.1:45918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:48:48.887084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.019579ms","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-22T01:48:48.887111Z","caller":"traceutil/trace.go:171","msg":"trace[1775498660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"607.085611ms","start":"2026-09-22T01:48:48.280017Z","end":"2026-09-22T01:48:48.887102Z","steps":["trace[1775498660] 'agreement among raft nodes before linearized reading' (duration: 606.988219ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:48.88714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:48.279996Z","time spent":"607.136001ms","remote":"127.0.0.1:46074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-22T01:48:50.367489Z","caller":"traceutil/trace.go:171","msg":"trace[2039386987] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"153.461383ms","start":"2026-09-22T01:48:50.213996Z","end":"2026-09-22T01:48:50.367457Z","steps":["trace[2039386987] 'process raft request' (duration: 144.347389ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:50.384592Z","caller":"traceutil/trace.go:171","msg":"trace[2079063858] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"169.247219ms","start":"2026-09-22T01:48:50.215322Z","end":"2026-09-22T01:48:50.384569Z","steps":["trace[2079063858] 'process raft request' (duration: 169.008415ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:50.384975Z","caller":"traceutil/trace.go:171","msg":"trace[1071617678] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"168.178544ms","start":"2026-09-22T01:48:50.216775Z","end":"2026-09-22T01:48:50.384953Z","steps":["trace[1071617678] 'read index received' (duration: 141.641222ms)","trace[1071617678] 'applied index is now lower than readState.Index' (duration: 26.536852ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:48:50.385008Z","caller":"traceutil/trace.go:171","msg":"trace[1224304016] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"101.447363ms","start":"2026-09-22T01:48:50.283526Z","end":"2026-09-22T01:48:50.384974Z","steps":["trace[1224304016] 'process raft request' (duration: 101.068118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:50.385069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.279185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:48:50.385197Z","caller":"traceutil/trace.go:171","msg":"trace[1788564449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"168.340596ms","start":"2026-09-22T01:48:50.216754Z","end":"2026-09-22T01:48:50.385094Z","steps":["trace[1788564449] 'agreement among raft nodes before linearized reading' (duration: 168.275285ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:55.971471Z","caller":"traceutil/trace.go:171","msg":"trace[1347941773] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"103.16831ms","start":"2026-09-22T01:50:55.868274Z","end":"2026-09-22T01:50:55.971442Z","steps":["trace[1347941773] 'process raft request' (duration: 102.717615ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:56.299615Z","caller":"traceutil/trace.go:171","msg":"trace[1923493140] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"143.676141ms","start":"2026-09-22T01:50:56.155906Z","end":"2026-09-22T01:50:56.299582Z","steps":["trace[1923493140] 'process raft request' (duration: 104.667342ms)","trace[1923493140] 'compare' (duration: 38.881028ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:50:56.654397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.902416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-09-22T01:50:56.65454Z","caller":"traceutil/trace.go:171","msg":"trace[1961094777] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2119; }","duration":"216.105068ms","start":"2026-09-22T01:50:56.438413Z","end":"2026-09-22T01:50:56.654518Z","steps":["trace[1961094777] 'range keys from in-memory index tree' (duration: 215.695994ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:50:56.655376Z","caller":"traceutil/trace.go:171","msg":"trace[1913946904] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"121.369467ms","start":"2026-09-22T01:50:56.533977Z","end":"2026-09-22T01:50:56.655346Z","steps":["trace[1913946904] 'process raft request' (duration: 121.020843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:50:57.137545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.88785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:50:57.137702Z","caller":"traceutil/trace.go:171","msg":"trace[1191874725] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"248.430773ms","start":"2026-09-22T01:50:56.889242Z","end":"2026-09-22T01:50:57.137673Z","steps":["trace[1191874725] 'process raft request' (duration: 139.331541ms)","trace[1191874725] 'compare' (duration: 108.79261ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:52:38.436617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.880569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:52:38.436937Z","caller":"traceutil/trace.go:171","msg":"trace[379186866] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"105.857383ms","start":"2026-09-22T01:52:38.331053Z","end":"2026-09-22T01:52:38.436911Z","steps":["trace[379186866] 'compare' (duration: 101.583737ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:38.624126Z","caller":"traceutil/trace.go:171","msg":"trace[824833675] transaction","detail":"{read_only:false; response_revision:2773; number_of_response:1; }","duration":"205.549144ms","start":"2026-09-22T01:52:38.418552Z","end":"2026-09-22T01:52:38.624102Z","steps":["trace[824833675] 'process raft request' (duration: 200.47815ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:52:38.624273Z","caller":"traceutil/trace.go:171","msg":"trace[1120543460] linearizableReadLoop","detail":"{readStateIndex:2872; appliedIndex:2871; }","duration":"154.09681ms","start":"2026-09-22T01:52:38.470001Z","end":"2026-09-22T01:52:38.624097Z","steps":["trace[1120543460] 'read index received' (duration: 148.970136ms)","trace[1120543460] 'applied index is now lower than readState.Index' (duration: 5.125554ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:52:38.624378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.352872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-22T01:52:38.624462Z","caller":"traceutil/trace.go:171","msg":"trace[546243858] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2773; }","duration":"154.452073ms","start":"2026-09-22T01:52:38.469995Z","end":"2026-09-22T01:52:38.624447Z","steps":["trace[546243858] 'agreement among raft nodes before linearized reading' (duration: 154.300062ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:52:38.624695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.247284ms","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-22T01:52:38.624741Z","caller":"traceutil/trace.go:171","msg":"trace[1499196495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2773; }","duration":"101.355025ms","start":"2026-09-22T01:52:38.523372Z","end":"2026-09-22T01:52:38.624727Z","steps":["trace[1499196495] 'agreement among raft nodes before linearized reading' (duration: 101.104443ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:00.521131Z","caller":"traceutil/trace.go:171","msg":"trace[872805858] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"106.361893ms","start":"2026-09-22T01:57:00.414746Z","end":"2026-09-22T01:57:00.521108Z","steps":["trace[872805858] 'process raft request' (duration: 106.214632ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:01.633745Z","caller":"traceutil/trace.go:171","msg":"trace[2005209060] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"142.317828ms","start":"2026-09-22T01:57:01.491404Z","end":"2026-09-22T01:57:01.633722Z","steps":["trace[2005209060] 'process raft request' (duration: 57.122715ms)","trace[2005209060] 'compare' (duration: 85.032812ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:01.63405Z","caller":"traceutil/trace.go:171","msg":"trace[125972086] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4956; }","duration":"132.163213ms","start":"2026-09-22T01:57:01.501862Z","end":"2026-09-22T01:57:01.634025Z","steps":["trace[125972086] 'read index received' (duration: 46.595546ms)","trace[125972086] 'applied index is now lower than readState.Index' (duration: 85.566747ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:01.634077Z","caller":"traceutil/trace.go:171","msg":"trace[1658214658] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"142.577931ms","start":"2026-09-22T01:57:01.491443Z","end":"2026-09-22T01:57:01.634021Z","steps":["trace[1658214658] 'process raft request' (duration: 142.242608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:01.634286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.406635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana.18d781e8c78c2a98\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-09-22T01:57:01.634325Z","caller":"traceutil/trace.go:171","msg":"trace[327145024] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana.18d781e8c78c2a98; range_end:; response_count:1; response_revision:4783; }","duration":"132.496115ms","start":"2026-09-22T01:57:01.501818Z","end":"2026-09-22T01:57:01.634314Z","steps":["trace[327145024] 'agreement among raft nodes before linearized reading' (duration: 132.337954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:01.634323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.758588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T01:57:01.63439Z","caller":"traceutil/trace.go:171","msg":"trace[1095676033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4783; }","duration":"105.850259ms","start":"2026-09-22T01:57:01.528528Z","end":"2026-09-22T01:57:01.634378Z","steps":["trace[1095676033] 'agreement among raft nodes before linearized reading' (duration: 105.687017ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:01.814893Z","caller":"traceutil/trace.go:171","msg":"trace[2060417241] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"177.913261ms","start":"2026-09-22T01:57:01.636951Z","end":"2026-09-22T01:57:01.814864Z","steps":["trace[2060417241] 'process raft request' (duration: 146.347162ms)","trace[2060417241] 'compare' (duration: 31.430817ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:01.824891Z","caller":"traceutil/trace.go:171","msg":"trace[991321016] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"186.479464ms","start":"2026-09-22T01:57:01.638383Z","end":"2026-09-22T01:57:01.824862Z","steps":["trace[991321016] 'process raft request' (duration: 186.285882ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:01.950718Z","caller":"traceutil/trace.go:171","msg":"trace[1414628602] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"105.629647ms","start":"2026-09-22T01:57:01.845055Z","end":"2026-09-22T01:57:01.950685Z","steps":["trace[1414628602] 'process raft request' (duration: 100.923447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:04.551509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.713109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-pck9x\" ","response":"range_response_count:1 size:9757"} {"level":"info","ts":"2026-09-22T01:57:04.551586Z","caller":"traceutil/trace.go:171","msg":"trace[320548716] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-pck9x; range_end:; response_count:1; response_revision:4860; }","duration":"104.855051ms","start":"2026-09-22T01:57:04.446713Z","end":"2026-09-22T01:57:04.551568Z","steps":["trace[320548716] 'range keys from in-memory index tree' (duration: 104.468117ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:04.551572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.990208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-09-22T01:57:04.551651Z","caller":"traceutil/trace.go:171","msg":"trace[1246174611] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4860; }","duration":"292.090209ms","start":"2026-09-22T01:57:04.259541Z","end":"2026-09-22T01:57:04.551631Z","steps":["trace[1246174611] 'range keys from in-memory index tree' (duration: 291.825347ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:04.554702Z","caller":"traceutil/trace.go:171","msg":"trace[2062342753] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"103.295667ms","start":"2026-09-22T01:57:04.451377Z","end":"2026-09-22T01:57:04.554673Z","steps":["trace[2062342753] 'process raft request' (duration: 102.962364ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:08.830726Z","caller":"traceutil/trace.go:171","msg":"trace[810873254] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"110.526449ms","start":"2026-09-22T01:57:08.720173Z","end":"2026-09-22T01:57:08.830699Z","steps":["trace[810873254] 'process raft request' (duration: 103.703561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:08.831305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.200044ms","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-22T01:57:08.831404Z","caller":"traceutil/trace.go:171","msg":"trace[1786164026] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4912; }","duration":"111.307515ms","start":"2026-09-22T01:57:08.72008Z","end":"2026-09-22T01:57:08.831388Z","steps":["trace[1786164026] 'agreement among raft nodes before linearized reading' (duration: 110.557168ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:09.105809Z","caller":"traceutil/trace.go:171","msg":"trace[1359074443] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"145.678747ms","start":"2026-09-22T01:57:08.960099Z","end":"2026-09-22T01:57:09.105777Z","steps":["trace[1359074443] 'process raft request' (duration: 100.188861ms)","trace[1359074443] 'compare' (duration: 45.179703ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:09.106022Z","caller":"traceutil/trace.go:171","msg":"trace[202679960] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"122.733792ms","start":"2026-09-22T01:57:08.983279Z","end":"2026-09-22T01:57:09.106013Z","steps":["trace[202679960] 'process raft request' (duration: 122.46182ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:09.105874Z","caller":"traceutil/trace.go:171","msg":"trace[606239524] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5113; }","duration":"139.710466ms","start":"2026-09-22T01:57:08.966146Z","end":"2026-09-22T01:57:09.105857Z","steps":["trace[606239524] 'read index received' (duration: 94.18708ms)","trace[606239524] 'applied index is now lower than readState.Index' (duration: 45.522486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:09.106381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.190583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-09-22T01:57:09.106412Z","caller":"traceutil/trace.go:171","msg":"trace[2123170085] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4933; }","duration":"145.245153ms","start":"2026-09-22T01:57:08.961159Z","end":"2026-09-22T01:57:09.106404Z","steps":["trace[2123170085] 'agreement among raft nodes before linearized reading' (duration: 144.752389ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:09.106563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.76192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"warn","ts":"2026-09-22T01:57:09.10661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.845862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-09-22T01:57:09.106641Z","caller":"traceutil/trace.go:171","msg":"trace[424315257] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4934; }","duration":"137.859701ms","start":"2026-09-22T01:57:08.968765Z","end":"2026-09-22T01:57:09.106625Z","steps":["trace[424315257] 'agreement among raft nodes before linearized reading' (duration: 137.571409ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:09.106681Z","caller":"traceutil/trace.go:171","msg":"trace[753890321] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4934; }","duration":"136.922952ms","start":"2026-09-22T01:57:08.969741Z","end":"2026-09-22T01:57:09.106664Z","steps":["trace[753890321] 'agreement among raft nodes before linearized reading' (duration: 136.70066ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:10.266887Z","caller":"traceutil/trace.go:171","msg":"trace[1981272480] linearizableReadLoop","detail":"{readStateIndex:5144; appliedIndex:5143; }","duration":"127.430632ms","start":"2026-09-22T01:57:10.139433Z","end":"2026-09-22T01:57:10.266864Z","steps":["trace[1981272480] 'read index received' (duration: 126.886307ms)","trace[1981272480] 'applied index is now lower than readState.Index' (duration: 543.215µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:10.266987Z","caller":"traceutil/trace.go:171","msg":"trace[29681165] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"130.410067ms","start":"2026-09-22T01:57:10.136548Z","end":"2026-09-22T01:57:10.266958Z","steps":["trace[29681165] 'process raft request' (duration: 129.825012ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:10.267138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.691055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-22T01:57:10.267154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.937582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-22T01:57:10.267191Z","caller":"traceutil/trace.go:171","msg":"trace[851253199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4962; }","duration":"127.789655ms","start":"2026-09-22T01:57:10.139385Z","end":"2026-09-22T01:57:10.267175Z","steps":["trace[851253199] 'agreement among raft nodes before linearized reading' (duration: 127.606824ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:10.267248Z","caller":"traceutil/trace.go:171","msg":"trace[1703327457] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4962; }","duration":"111.078613ms","start":"2026-09-22T01:57:10.156149Z","end":"2026-09-22T01:57:10.267228Z","steps":["trace[1703327457] 'agreement among raft nodes before linearized reading' (duration: 110.864591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:10.267537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.413428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-22T01:57:10.267623Z","caller":"traceutil/trace.go:171","msg":"trace[785727819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4962; }","duration":"110.533739ms","start":"2026-09-22T01:57:10.157069Z","end":"2026-09-22T01:57:10.267602Z","steps":["trace[785727819] 'agreement among raft nodes before linearized reading' (duration: 110.252416ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:10.600101Z","caller":"traceutil/trace.go:171","msg":"trace[1947569319] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"328.194127ms","start":"2026-09-22T01:57:10.27189Z","end":"2026-09-22T01:57:10.600085Z","steps":["trace[1947569319] 'process raft request' (duration: 328.078606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:10.600215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:10.271869Z","time spent":"328.276748ms","remote":"127.0.0.1:46074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-22T01:57:10.600269Z","caller":"traceutil/trace.go:171","msg":"trace[1307345643] linearizableReadLoop","detail":"{readStateIndex:5147; appliedIndex:5144; }","duration":"321.589721ms","start":"2026-09-22T01:57:10.278662Z","end":"2026-09-22T01:57:10.600252Z","steps":["trace[1307345643] 'read index received' (duration: 23.094126ms)","trace[1307345643] 'applied index is now lower than readState.Index' (duration: 298.494725ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:10.60044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.776382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83b027f9-bd00-47c6-bc52-7d40791e6f48\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-22T01:57:10.600464Z","caller":"traceutil/trace.go:171","msg":"trace[598797659] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83b027f9-bd00-47c6-bc52-7d40791e6f48; range_end:; response_count:1; response_revision:4965; }","duration":"321.829003ms","start":"2026-09-22T01:57:10.278629Z","end":"2026-09-22T01:57:10.600458Z","steps":["trace[598797659] 'agreement among raft nodes before linearized reading' (duration: 321.749543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:10.60048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:10.278618Z","time spent":"321.858644ms","remote":"127.0.0.1:45998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83b027f9-bd00-47c6-bc52-7d40791e6f48\" "} {"level":"warn","ts":"2026-09-22T01:57:10.600524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.0297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-586d55b8ff-b9w8p.18d781ec7514ad68\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-09-22T01:57:10.60055Z","caller":"traceutil/trace.go:171","msg":"trace[105593159] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"328.034155ms","start":"2026-09-22T01:57:10.272511Z","end":"2026-09-22T01:57:10.600545Z","steps":["trace[105593159] 'process raft request' (duration: 327.550101ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:10.600555Z","caller":"traceutil/trace.go:171","msg":"trace[1034041832] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-586d55b8ff-b9w8p.18d781ec7514ad68; range_end:; response_count:1; response_revision:4965; }","duration":"106.07277ms","start":"2026-09-22T01:57:10.494473Z","end":"2026-09-22T01:57:10.600546Z","steps":["trace[1034041832] 'agreement among raft nodes before linearized reading' (duration: 105.955339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:10.600581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:10.272491Z","time spent":"328.073005ms","remote":"127.0.0.1:46074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:57:10.600738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.871649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83b027f9-bd00-47c6-bc52-7d40791e6f48\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-22T01:57:10.600763Z","caller":"traceutil/trace.go:171","msg":"trace[542998919] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83b027f9-bd00-47c6-bc52-7d40791e6f48; range_end:; response_count:1; response_revision:4965; }","duration":"105.922929ms","start":"2026-09-22T01:57:10.494834Z","end":"2026-09-22T01:57:10.600757Z","steps":["trace[542998919] 'agreement among raft nodes before linearized reading' (duration: 105.856038ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:14.111043Z","caller":"traceutil/trace.go:171","msg":"trace[860357566] linearizableReadLoop","detail":"{readStateIndex:5203; appliedIndex:5202; }","duration":"129.848892ms","start":"2026-09-22T01:57:13.981171Z","end":"2026-09-22T01:57:14.11102Z","steps":["trace[860357566] 'read index received' (duration: 129.718221ms)","trace[860357566] 'applied index is now lower than readState.Index' (duration: 129.621µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:14.111099Z","caller":"traceutil/trace.go:171","msg":"trace[2145221948] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"148.726223ms","start":"2026-09-22T01:57:13.962345Z","end":"2026-09-22T01:57:14.111072Z","steps":["trace[2145221948] 'process raft request' (duration: 148.516801ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:14.111303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.110934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-pck9x.18d781eb0cce3f12\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-09-22T01:57:14.111357Z","caller":"traceutil/trace.go:171","msg":"trace[699334359] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-pck9x.18d781eb0cce3f12; range_end:; response_count:1; response_revision:5020; }","duration":"130.225796ms","start":"2026-09-22T01:57:13.981105Z","end":"2026-09-22T01:57:14.111331Z","steps":["trace[699334359] 'agreement among raft nodes before linearized reading' (duration: 130.073365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:14.111548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.316262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-25qbb\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-09-22T01:57:14.111632Z","caller":"traceutil/trace.go:171","msg":"trace[617876257] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-25qbb; range_end:; response_count:1; response_revision:5020; }","duration":"120.405692ms","start":"2026-09-22T01:57:13.99118Z","end":"2026-09-22T01:57:14.111585Z","steps":["trace[617876257] 'agreement among raft nodes before linearized reading' (duration: 120.17642ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:24.032195Z","caller":"traceutil/trace.go:171","msg":"trace[136540787] linearizableReadLoop","detail":"{readStateIndex:5329; appliedIndex:5328; }","duration":"372.714764ms","start":"2026-09-22T01:57:23.659459Z","end":"2026-09-22T01:57:24.032174Z","steps":["trace[136540787] 'read index received' (duration: 372.674994ms)","trace[136540787] 'applied index is now lower than readState.Index' (duration: 39µs)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:24.032236Z","caller":"traceutil/trace.go:171","msg":"trace[64971880] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"390.102642ms","start":"2026-09-22T01:57:23.64211Z","end":"2026-09-22T01:57:24.032213Z","steps":["trace[64971880] 'process raft request' (duration: 389.952331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.032352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:23.642081Z","time spent":"390.208123ms","remote":"127.0.0.1:46074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:57:24.032374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.904026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-09-22T01:57:24.032403Z","caller":"traceutil/trace.go:171","msg":"trace[726061119] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5141; }","duration":"372.979406ms","start":"2026-09-22T01:57:23.659415Z","end":"2026-09-22T01:57:24.032394Z","steps":["trace[726061119] 'agreement among raft nodes before linearized reading' (duration: 372.859355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.032428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:23.65938Z","time spent":"373.027338ms","remote":"127.0.0.1:45998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10415,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-09-22T01:57:24.034366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.312947ms","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-22T01:57:24.034427Z","caller":"traceutil/trace.go:171","msg":"trace[117707668] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5142; }","duration":"342.422047ms","start":"2026-09-22T01:57:23.691989Z","end":"2026-09-22T01:57:24.034411Z","steps":["trace[117707668] 'agreement among raft nodes before linearized reading' (duration: 342.259876ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.034466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:57:23.691978Z","time spent":"342.478598ms","remote":"127.0.0.1:45982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-09-22T01:57:24.034471Z","caller":"traceutil/trace.go:171","msg":"trace[988706369] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"230.164385ms","start":"2026-09-22T01:57:23.804284Z","end":"2026-09-22T01:57:24.034448Z","steps":["trace[988706369] 'process raft request' (duration: 229.878352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.034686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.534664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-22T01:57:24.034737Z","caller":"traceutil/trace.go:171","msg":"trace[1607485207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5142; }","duration":"113.625975ms","start":"2026-09-22T01:57:23.921096Z","end":"2026-09-22T01:57:24.034722Z","steps":["trace[1607485207] 'agreement among raft nodes before linearized reading' (duration: 113.493754ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:24.243327Z","caller":"traceutil/trace.go:171","msg":"trace[1882276844] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5332; }","duration":"188.645541ms","start":"2026-09-22T01:57:24.054663Z","end":"2026-09-22T01:57:24.243309Z","steps":["trace[1882276844] 'read index received' (duration: 169.229507ms)","trace[1882276844] 'applied index is now lower than readState.Index' (duration: 19.415174ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:24.243509Z","caller":"traceutil/trace.go:171","msg":"trace[927131554] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"190.581298ms","start":"2026-09-22T01:57:24.052918Z","end":"2026-09-22T01:57:24.243499Z","steps":["trace[927131554] 'process raft request' (duration: 170.999882ms)","trace[927131554] 'compare' (duration: 19.319644ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:57:24.243795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.47256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-22T01:57:24.243875Z","caller":"traceutil/trace.go:171","msg":"trace[451923141] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5145; }","duration":"188.589671ms","start":"2026-09-22T01:57:24.055264Z","end":"2026-09-22T01:57:24.243853Z","steps":["trace[451923141] 'agreement among raft nodes before linearized reading' (duration: 188.338669ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.243912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.238406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-09-22T01:57:24.243938Z","caller":"traceutil/trace.go:171","msg":"trace[1316991205] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5145; }","duration":"189.311258ms","start":"2026-09-22T01:57:24.05462Z","end":"2026-09-22T01:57:24.243931Z","steps":["trace[1316991205] 'agreement among raft nodes before linearized reading' (duration: 189.206637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.502279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.148232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-22T01:57:24.502485Z","caller":"traceutil/trace.go:171","msg":"trace[819347240] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"182.929624ms","start":"2026-09-22T01:57:24.319488Z","end":"2026-09-22T01:57:24.502418Z","steps":["trace[819347240] 'process raft request' (duration: 23.53909ms)","trace[819347240] 'compare' (duration: 159.020601ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:24.570635Z","caller":"traceutil/trace.go:171","msg":"trace[1147764979] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"247.181079ms","start":"2026-09-22T01:57:24.323432Z","end":"2026-09-22T01:57:24.570613Z","steps":["trace[1147764979] 'process raft request' (duration: 247.011567ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:57:24.570733Z","caller":"traceutil/trace.go:171","msg":"trace[784758309] linearizableReadLoop","detail":"{readStateIndex:5341; appliedIndex:5339; }","duration":"245.674746ms","start":"2026-09-22T01:57:24.325039Z","end":"2026-09-22T01:57:24.570714Z","steps":["trace[784758309] 'read index received' (duration: 17.931683ms)","trace[784758309] 'applied index is now lower than readState.Index' (duration: 227.741693ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:57:24.570844Z","caller":"traceutil/trace.go:171","msg":"trace[16212746] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"236.861731ms","start":"2026-09-22T01:57:24.333953Z","end":"2026-09-22T01:57:24.570815Z","steps":["trace[16212746] 'process raft request' (duration: 236.607019ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.571083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.023109ms","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-22T01:57:24.571175Z","caller":"traceutil/trace.go:171","msg":"trace[226088173] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5154; }","duration":"246.14386ms","start":"2026-09-22T01:57:24.325016Z","end":"2026-09-22T01:57:24.57116Z","steps":["trace[226088173] 'agreement among raft nodes before linearized reading' (duration: 245.781637ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.592415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.553387ms","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-22T01:57:24.592549Z","caller":"traceutil/trace.go:171","msg":"trace[1327816979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5155; }","duration":"237.705739ms","start":"2026-09-22T01:57:24.354827Z","end":"2026-09-22T01:57:24.592532Z","steps":["trace[1327816979] 'agreement among raft nodes before linearized reading' (duration: 237.470227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:57:24.592415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.776567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:57:24.592781Z","caller":"traceutil/trace.go:171","msg":"trace[1137938716] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5155; }","duration":"246.10439ms","start":"2026-09-22T01:57:24.346589Z","end":"2026-09-22T01:57:24.592694Z","steps":["trace[1137938716] 'agreement among raft nodes before linearized reading' (duration: 245.758277ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:58:03.153771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2906} {"level":"info","ts":"2026-09-22T01:58:03.393445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2906,"took":"238.231953ms","hash":3822305304,"current-db-size-bytes":53133312,"current-db-size":"53 MB","current-db-size-in-use-bytes":28352512,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-22T01:58:03.393524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3822305304,"revision":2906,"compact-revision":-1} {"level":"info","ts":"2026-09-22T02:03:03.161082Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5486} {"level":"info","ts":"2026-09-22T02:03:03.249973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5486,"took":"87.375625ms","hash":363943614,"current-db-size-bytes":53133312,"current-db-size":"53 MB","current-db-size-in-use-bytes":18534400,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-22T02:03:03.250038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":363943614,"revision":5486,"compact-revision":2906}