{"level":"warn","ts":"2026-09-02T01:08:21.925514Z","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-02T01:08:21.925624Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.45: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.45:2380","--initial-cluster=instance=https://162.253.55.45:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.45:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.45: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-02T01:08:21.925694Z","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-02T01:08:21.925705Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-09-02T01:08:21.925724Z","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-02T01:08:21.926314Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"]} {"level":"info","ts":"2026-09-02T01:08:21.926386Z","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.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.45: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-02T01:08:21.934371Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.786761ms"} {"level":"info","ts":"2026-09-02T01:08:21.940537Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fbe9b088d8de907a","cluster-id":"332637b4e3714c3a"} {"level":"info","ts":"2026-09-02T01:08:21.940606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=()"} {"level":"info","ts":"2026-09-02T01:08:21.940628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 0"} {"level":"info","ts":"2026-09-02T01:08:21.940641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fbe9b088d8de907a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-02T01:08:21.940649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became follower at term 1"} {"level":"info","ts":"2026-09-02T01:08:21.940682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"warn","ts":"2026-09-02T01:08:21.95349Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-02T01:08:21.955874Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-02T01:08:21.95751Z","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-02T01:08:21.959839Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fbe9b088d8de907a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-02T01:08:21.962651Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fbe9b088d8de907a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-02T01:08:21.962525Z","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-02T01:08:21.962887Z","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-02T01:08:21.962924Z","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-02T01:08:21.963662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a switched to configuration voters=(18152233874894000250)"} {"level":"info","ts":"2026-09-02T01:08:21.963786Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","added-peer-id":"fbe9b088d8de907a","added-peer-peer-urls":["https://162.253.55.45:2380"]} {"level":"info","ts":"2026-09-02T01:08:21.965255Z","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-02T01:08:21.96539Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-09-02T01:08:21.965542Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.45:2380"} {"level":"info","ts":"2026-09-02T01:08:21.966317Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fbe9b088d8de907a","initial-advertise-peer-urls":["https://162.253.55.45:2380"],"listen-peer-urls":["https://162.253.55.45:2380"],"advertise-client-urls":["https://162.253.55.45:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.45:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-02T01:08:21.966368Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-02T01:08:22.94093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a is starting a new election at term 1"} {"level":"info","ts":"2026-09-02T01:08:22.941021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-02T01:08:22.941064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgPreVoteResp from fbe9b088d8de907a at term 1"} {"level":"info","ts":"2026-09-02T01:08:22.941082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became candidate at term 2"} {"level":"info","ts":"2026-09-02T01:08:22.941092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a received MsgVoteResp from fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-09-02T01:08:22.941113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fbe9b088d8de907a became leader at term 2"} {"level":"info","ts":"2026-09-02T01:08:22.941125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fbe9b088d8de907a elected leader fbe9b088d8de907a at term 2"} {"level":"info","ts":"2026-09-02T01:08:22.942425Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:08:22.943463Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fbe9b088d8de907a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.45:2379]}","request-path":"/0/members/fbe9b088d8de907a/attributes","cluster-id":"332637b4e3714c3a","publish-timeout":"7s"} {"level":"info","ts":"2026-09-02T01:08:22.943493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T01:08:22.943627Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T01:08:22.944107Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"332637b4e3714c3a","local-member-id":"fbe9b088d8de907a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:08:22.944231Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:08:22.944267Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:08:22.945034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-02T01:08:22.945053Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-02T01:08:22.945071Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-02T01:08:22.947029Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.45:2379"} {"level":"info","ts":"2026-09-02T01:08:32.779173Z","caller":"traceutil/trace.go:171","msg":"trace[629502515] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"128.596026ms","start":"2026-09-02T01:08:32.650539Z","end":"2026-09-02T01:08:32.779135Z","steps":["trace[629502515] 'process raft request' (duration: 93.686087ms)","trace[629502515] 'compare' (duration: 34.4363ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:08:32.952354Z","caller":"traceutil/trace.go:171","msg":"trace[2137607031] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:369; }","duration":"108.608169ms","start":"2026-09-02T01:08:32.843722Z","end":"2026-09-02T01:08:32.95233Z","steps":["trace[2137607031] 'read index received' (duration: 4.676325ms)","trace[2137607031] 'applied index is now lower than readState.Index' (duration: 103.925714ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:08:32.952351Z","caller":"traceutil/trace.go:171","msg":"trace[792374889] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"139.596247ms","start":"2026-09-02T01:08:32.812724Z","end":"2026-09-02T01:08:32.952321Z","steps":["trace[792374889] 'process raft request' (duration: 88.367899ms)","trace[792374889] 'compare' (duration: 51.000934ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:08:32.952629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.893416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-09-02T01:08:32.952718Z","caller":"traceutil/trace.go:171","msg":"trace[2135325045] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:362; }","duration":"109.036938ms","start":"2026-09-02T01:08:32.843665Z","end":"2026-09-02T01:08:32.952702Z","steps":["trace[2135325045] 'agreement among raft nodes before linearized reading' (duration: 108.877195ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:08:32.952774Z","caller":"traceutil/trace.go:171","msg":"trace[1341351415] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"139.490086ms","start":"2026-09-02T01:08:32.813244Z","end":"2026-09-02T01:08:32.952734Z","steps":["trace[1341351415] 'process raft request' (duration: 139.000837ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:08:34.651049Z","caller":"traceutil/trace.go:171","msg":"trace[584976228] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"147.386284ms","start":"2026-09-02T01:08:34.503633Z","end":"2026-09-02T01:08:34.651019Z","steps":["trace[584976228] 'process raft request' (duration: 146.411976ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:08:34.696232Z","caller":"traceutil/trace.go:171","msg":"trace[1806324266] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"120.879132ms","start":"2026-09-02T01:08:34.575333Z","end":"2026-09-02T01:08:34.696212Z","steps":["trace[1806324266] 'process raft request' (duration: 120.79022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:08:35.246039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.950427ms","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-02T01:08:35.246122Z","caller":"traceutil/trace.go:171","msg":"trace[1465327579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"263.085249ms","start":"2026-09-02T01:08:34.983017Z","end":"2026-09-02T01:08:35.246103Z","steps":["trace[1465327579] 'range keys from in-memory index tree' (duration: 262.778784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:08:35.246133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.402938ms","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-02T01:08:35.246241Z","caller":"traceutil/trace.go:171","msg":"trace[1657240735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"277.570171ms","start":"2026-09-02T01:08:34.968644Z","end":"2026-09-02T01:08:35.246214Z","steps":["trace[1657240735] 'range keys from in-memory index tree' (duration: 277.192914ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:08:35.421636Z","caller":"traceutil/trace.go:171","msg":"trace[904006908] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"170.358974ms","start":"2026-09-02T01:08:35.251252Z","end":"2026-09-02T01:08:35.421611Z","steps":["trace[904006908] 'process raft request' (duration: 170.214971ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:08:35.422395Z","caller":"traceutil/trace.go:171","msg":"trace[324730514] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:416; }","duration":"144.355386ms","start":"2026-09-02T01:08:35.278018Z","end":"2026-09-02T01:08:35.422373Z","steps":["trace[324730514] 'read index received' (duration: 143.433129ms)","trace[324730514] 'applied index is now lower than readState.Index' (duration: 921.397µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:08:35.422439Z","caller":"traceutil/trace.go:171","msg":"trace[2083684157] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"170.390665ms","start":"2026-09-02T01:08:35.252026Z","end":"2026-09-02T01:08:35.422417Z","steps":["trace[2083684157] 'process raft request' (duration: 170.284033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:08:35.422623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.478418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:08:35.422685Z","caller":"traceutil/trace.go:171","msg":"trace[860776177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"144.697681ms","start":"2026-09-02T01:08:35.277969Z","end":"2026-09-02T01:08:35.422666Z","steps":["trace[860776177] 'agreement among raft nodes before linearized reading' (duration: 144.487507ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:06.939552Z","caller":"traceutil/trace.go:171","msg":"trace[1831667212] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"171.477472ms","start":"2026-09-02T01:09:06.768034Z","end":"2026-09-02T01:09:06.939511Z","steps":["trace[1831667212] 'process raft request' (duration: 162.295967ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:06.940362Z","caller":"traceutil/trace.go:171","msg":"trace[1897361356] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"162.573541ms","start":"2026-09-02T01:09:06.777745Z","end":"2026-09-02T01:09:06.940318Z","steps":["trace[1897361356] 'process raft request' (duration: 162.061163ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:06.94065Z","caller":"traceutil/trace.go:171","msg":"trace[68002378] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:602; }","duration":"140.190878ms","start":"2026-09-02T01:09:06.800424Z","end":"2026-09-02T01:09:06.940615Z","steps":["trace[68002378] 'read index received' (duration: 129.795063ms)","trace[68002378] 'applied index is now lower than readState.Index' (duration: 10.393395ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:09:06.940769Z","caller":"traceutil/trace.go:171","msg":"trace[416440521] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"151.746579ms","start":"2026-09-02T01:09:06.78895Z","end":"2026-09-02T01:09:06.940696Z","steps":["trace[416440521] 'process raft request' (duration: 151.268331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:09:06.940867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.418591ms","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-02T01:09:06.941165Z","caller":"traceutil/trace.go:171","msg":"trace[2132675500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"140.726006ms","start":"2026-09-02T01:09:06.800414Z","end":"2026-09-02T01:09:06.94114Z","steps":["trace[2132675500] 'agreement among raft nodes before linearized reading' (duration: 140.30625ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:09:08.393961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.297636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:09:08.394067Z","caller":"traceutil/trace.go:171","msg":"trace[1922292563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"116.46816ms","start":"2026-09-02T01:09:08.277576Z","end":"2026-09-02T01:09:08.394044Z","steps":["trace[1922292563] 'range keys from in-memory index tree' (duration: 116.221084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:09:13.904164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.150902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-02T01:09:13.904164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.305485ms","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-02T01:09:13.904242Z","caller":"traceutil/trace.go:171","msg":"trace[934303955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"217.241843ms","start":"2026-09-02T01:09:13.686983Z","end":"2026-09-02T01:09:13.904224Z","steps":["trace[934303955] 'range keys from in-memory index tree' (duration: 217.056351ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:13.904256Z","caller":"traceutil/trace.go:171","msg":"trace[1778687495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"217.444377ms","start":"2026-09-02T01:09:13.68679Z","end":"2026-09-02T01:09:13.904235Z","steps":["trace[1778687495] 'range keys from in-memory index tree' (duration: 217.132822ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:14.091396Z","caller":"traceutil/trace.go:171","msg":"trace[1068343617] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"186.709139ms","start":"2026-09-02T01:09:13.904586Z","end":"2026-09-02T01:09:14.091295Z","steps":["trace[1068343617] 'read index received' (duration: 181.876804ms)","trace[1068343617] 'applied index is now lower than readState.Index' (duration: 4.830365ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:09:14.091834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.223207ms","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-02T01:09:14.091944Z","caller":"traceutil/trace.go:171","msg":"trace[1544968641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"187.36542ms","start":"2026-09-02T01:09:13.904557Z","end":"2026-09-02T01:09:14.091922Z","steps":["trace[1544968641] 'agreement among raft nodes before linearized reading' (duration: 187.020354ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:14.092837Z","caller":"traceutil/trace.go:171","msg":"trace[2111340060] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"207.041252ms","start":"2026-09-02T01:09:13.885754Z","end":"2026-09-02T01:09:14.092795Z","steps":["trace[2111340060] 'process raft request' (duration: 201.602688ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:14.099318Z","caller":"traceutil/trace.go:171","msg":"trace[292442649] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"191.004414ms","start":"2026-09-02T01:09:13.908296Z","end":"2026-09-02T01:09:14.0993Z","steps":["trace[292442649] 'process raft request' (duration: 190.976833ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:14.099544Z","caller":"traceutil/trace.go:171","msg":"trace[1635323242] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"191.35274ms","start":"2026-09-02T01:09:13.908185Z","end":"2026-09-02T01:09:14.099538Z","steps":["trace[1635323242] 'process raft request' (duration: 191.006014ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:09:14.70993Z","caller":"traceutil/trace.go:171","msg":"trace[701567102] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"432.040109ms","start":"2026-09-02T01:09:14.277868Z","end":"2026-09-02T01:09:14.709908Z","steps":["trace[701567102] 'read index received' (duration: 426.044406ms)","trace[701567102] 'applied index is now lower than readState.Index' (duration: 5.994373ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:09:14.710066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.181392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:09:14.710095Z","caller":"traceutil/trace.go:171","msg":"trace[428299185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"432.255643ms","start":"2026-09-02T01:09:14.27783Z","end":"2026-09-02T01:09:14.710086Z","steps":["trace[428299185] 'agreement among raft nodes before linearized reading' (duration: 432.177641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:09:14.710123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:09:14.277811Z","time spent":"432.305913ms","remote":"127.0.0.1:39262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-02T01:09:14.710215Z","caller":"traceutil/trace.go:171","msg":"trace[1159755116] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"548.985979ms","start":"2026-09-02T01:09:14.161189Z","end":"2026-09-02T01:09:14.710175Z","steps":["trace[1159755116] 'process raft request' (duration: 542.748382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:09:14.710744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:09:14.161169Z","time spent":"549.127461ms","remote":"127.0.0.1:39502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-02T01:09:57.245815Z","caller":"traceutil/trace.go:171","msg":"trace[1943948098] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"135.871523ms","start":"2026-09-02T01:09:57.10992Z","end":"2026-09-02T01:09:57.245791Z","steps":["trace[1943948098] 'process raft request' (duration: 135.347325ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:08.900734Z","caller":"traceutil/trace.go:171","msg":"trace[171717007] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"265.992797ms","start":"2026-09-02T01:10:08.634702Z","end":"2026-09-02T01:10:08.900695Z","steps":["trace[171717007] 'process raft request' (duration: 265.418619ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.146315Z","caller":"traceutil/trace.go:171","msg":"trace[230347429] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1521; }","duration":"105.915669ms","start":"2026-09-02T01:10:30.040376Z","end":"2026-09-02T01:10:30.146292Z","steps":["trace[230347429] 'read index received' (duration: 103.507117ms)","trace[230347429] 'applied index is now lower than readState.Index' (duration: 2.407762ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:10:30.146386Z","caller":"traceutil/trace.go:171","msg":"trace[2057464085] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"137.149242ms","start":"2026-09-02T01:10:30.009214Z","end":"2026-09-02T01:10:30.146363Z","steps":["trace[2057464085] 'process raft request' (duration: 134.660929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:10:30.146947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.551208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-487gb\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-09-02T01:10:30.146991Z","caller":"traceutil/trace.go:171","msg":"trace[1442954945] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-defaultbackend-6987ff55cf-487gb; range_end:; response_count:1; response_revision:1474; }","duration":"106.608718ms","start":"2026-09-02T01:10:30.040369Z","end":"2026-09-02T01:10:30.146978Z","steps":["trace[1442954945] 'agreement among raft nodes before linearized reading' (duration: 106.026411ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.149621Z","caller":"traceutil/trace.go:171","msg":"trace[1201786290] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"106.335594ms","start":"2026-09-02T01:10:30.043266Z","end":"2026-09-02T01:10:30.149601Z","steps":["trace[1201786290] 'process raft request' (duration: 105.923369ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.149666Z","caller":"traceutil/trace.go:171","msg":"trace[460713605] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"106.72351ms","start":"2026-09-02T01:10:30.042909Z","end":"2026-09-02T01:10:30.149633Z","steps":["trace[460713605] 'process raft request' (duration: 106.241694ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.14973Z","caller":"traceutil/trace.go:171","msg":"trace[685084790] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"106.097932ms","start":"2026-09-02T01:10:30.043615Z","end":"2026-09-02T01:10:30.149713Z","steps":["trace[685084790] 'process raft request' (duration: 105.672006ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.14996Z","caller":"traceutil/trace.go:171","msg":"trace[2074883583] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"107.115945ms","start":"2026-09-02T01:10:30.042833Z","end":"2026-09-02T01:10:30.149949Z","steps":["trace[2074883583] 'process raft request' (duration: 106.272284ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.150002Z","caller":"traceutil/trace.go:171","msg":"trace[909584794] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"107.233766ms","start":"2026-09-02T01:10:30.042755Z","end":"2026-09-02T01:10:30.149988Z","steps":["trace[909584794] 'process raft request' (duration: 106.248684ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.150349Z","caller":"traceutil/trace.go:171","msg":"trace[1315442045] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"106.895243ms","start":"2026-09-02T01:10:30.043445Z","end":"2026-09-02T01:10:30.15034Z","steps":["trace[1315442045] 'process raft request' (duration: 105.771638ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:30.671341Z","caller":"traceutil/trace.go:171","msg":"trace[716589794] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"139.530473ms","start":"2026-09-02T01:10:30.531781Z","end":"2026-09-02T01:10:30.671311Z","steps":["trace[716589794] 'process raft request' (duration: 123.383929ms)","trace[716589794] 'compare' (duration: 15.919911ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:10:30.67176Z","caller":"traceutil/trace.go:171","msg":"trace[1271869363] linearizableReadLoop","detail":"{readStateIndex:1536; appliedIndex:1534; }","duration":"116.226585ms","start":"2026-09-02T01:10:30.55551Z","end":"2026-09-02T01:10:30.671737Z","steps":["trace[1271869363] 'read index received' (duration: 99.762758ms)","trace[1271869363] 'applied index is now lower than readState.Index' (duration: 16.462787ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:10:30.6718Z","caller":"traceutil/trace.go:171","msg":"trace[2026719866] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"129.576351ms","start":"2026-09-02T01:10:30.542175Z","end":"2026-09-02T01:10:30.671751Z","steps":["trace[2026719866] 'process raft request' (duration: 129.358738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:10:30.671971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.422408ms","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-02T01:10:30.672014Z","caller":"traceutil/trace.go:171","msg":"trace[682674861] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1488; }","duration":"116.515359ms","start":"2026-09-02T01:10:30.555486Z","end":"2026-09-02T01:10:30.672001Z","steps":["trace[682674861] 'agreement among raft nodes before linearized reading' (duration: 116.354077ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:10:30.67864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.271169ms","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-02T01:10:30.678721Z","caller":"traceutil/trace.go:171","msg":"trace[1751768249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1488; }","duration":"108.405532ms","start":"2026-09-02T01:10:30.570295Z","end":"2026-09-02T01:10:30.6787Z","steps":["trace[1751768249] 'agreement among raft nodes before linearized reading' (duration: 108.037307ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:10:59.587909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.234053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-27ae164b-14a7-4ced-8a68-18758385d332\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-02T01:10:59.588019Z","caller":"traceutil/trace.go:171","msg":"trace[559273798] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-27ae164b-14a7-4ced-8a68-18758385d332; range_end:; response_count:1; response_revision:1946; }","duration":"185.405195ms","start":"2026-09-02T01:10:59.402588Z","end":"2026-09-02T01:10:59.587994Z","steps":["trace[559273798] 'range keys from in-memory index tree' (duration: 185.000399ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:10:59.789658Z","caller":"traceutil/trace.go:171","msg":"trace[42354498] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"327.883897ms","start":"2026-09-02T01:10:59.461752Z","end":"2026-09-02T01:10:59.789636Z","steps":["trace[42354498] 'read index received' (duration: 327.712015ms)","trace[42354498] 'applied index is now lower than readState.Index' (duration: 171.042µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:10:59.789873Z","caller":"traceutil/trace.go:171","msg":"trace[225844901] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"336.212474ms","start":"2026-09-02T01:10:59.453649Z","end":"2026-09-02T01:10:59.789861Z","steps":["trace[225844901] 'process raft request' (duration: 335.861279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:10:59.79014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.750979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-02T01:10:59.79022Z","caller":"traceutil/trace.go:171","msg":"trace[480818536] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1947; }","duration":"164.878371ms","start":"2026-09-02T01:10:59.625321Z","end":"2026-09-02T01:10:59.790199Z","steps":["trace[480818536] 'agreement among raft nodes before linearized reading' (duration: 164.629248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:10:59.790257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:10:59.453625Z","time spent":"336.291665ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:10:59.790616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.831558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-02T01:10:59.790698Z","caller":"traceutil/trace.go:171","msg":"trace[1600356624] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1947; }","duration":"328.969901ms","start":"2026-09-02T01:10:59.461708Z","end":"2026-09-02T01:10:59.790678Z","steps":["trace[1600356624] 'agreement among raft nodes before linearized reading' (duration: 328.715767ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:10:59.790741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:10:59.461688Z","time spent":"329.044062ms","remote":"127.0.0.1:35756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-02T01:11:00.287878Z","caller":"traceutil/trace.go:171","msg":"trace[1112526319] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"308.766419ms","start":"2026-09-02T01:10:59.979087Z","end":"2026-09-02T01:11:00.287854Z","steps":["trace[1112526319] 'process raft request' (duration: 308.572496ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:11:00.288038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:10:59.979063Z","time spent":"308.8862ms","remote":"127.0.0.1:39532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:11:13.590278Z","caller":"traceutil/trace.go:171","msg":"trace[1761720379] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"298.715736ms","start":"2026-09-02T01:11:13.29153Z","end":"2026-09-02T01:11:13.590245Z","steps":["trace[1761720379] 'process raft request' (duration: 272.26725ms)","trace[1761720379] 'compare' (duration: 26.025501ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:11:17.731946Z","caller":"traceutil/trace.go:171","msg":"trace[1529190606] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2170; }","duration":"173.88071ms","start":"2026-09-02T01:11:17.558034Z","end":"2026-09-02T01:11:17.731915Z","steps":["trace[1529190606] 'read index received' (duration: 173.655427ms)","trace[1529190606] 'applied index is now lower than readState.Index' (duration: 223.623µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:11:17.732102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.048074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:11:17.732191Z","caller":"traceutil/trace.go:171","msg":"trace[1328185813] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:2090; }","duration":"174.189955ms","start":"2026-09-02T01:11:17.55798Z","end":"2026-09-02T01:11:17.73217Z","steps":["trace[1328185813] 'agreement among raft nodes before linearized reading' (duration: 174.028763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:11:17.73233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.644771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-02T01:11:17.732534Z","caller":"traceutil/trace.go:171","msg":"trace[1042321383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2090; }","duration":"126.899693ms","start":"2026-09-02T01:11:17.605617Z","end":"2026-09-02T01:11:17.732517Z","steps":["trace[1042321383] 'agreement among raft nodes before linearized reading' (duration: 126.457438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:11:40.311037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.77134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:11:40.31176Z","caller":"traceutil/trace.go:171","msg":"trace[1570425674] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2218; }","duration":"162.551267ms","start":"2026-09-02T01:11:40.149186Z","end":"2026-09-02T01:11:40.311737Z","steps":["trace[1570425674] 'count revisions from in-memory index tree' (duration: 161.657375ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:11:40.986301Z","caller":"traceutil/trace.go:171","msg":"trace[979253352] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"123.476147ms","start":"2026-09-02T01:11:40.862804Z","end":"2026-09-02T01:11:40.98628Z","steps":["trace[979253352] 'process raft request' (duration: 123.343461ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:11:41.209658Z","caller":"traceutil/trace.go:171","msg":"trace[1567935721] linearizableReadLoop","detail":"{readStateIndex:2308; appliedIndex:2307; }","duration":"120.255914ms","start":"2026-09-02T01:11:41.089365Z","end":"2026-09-02T01:11:41.209621Z","steps":["trace[1567935721] 'read index received' (duration: 73.357092ms)","trace[1567935721] 'applied index is now lower than readState.Index' (duration: 46.897692ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:11:41.209753Z","caller":"traceutil/trace.go:171","msg":"trace[1493888296] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"150.318034ms","start":"2026-09-02T01:11:41.059411Z","end":"2026-09-02T01:11:41.209729Z","steps":["trace[1493888296] 'process raft request' (duration: 103.400672ms)","trace[1493888296] 'compare' (duration: 46.640209ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:11:41.210128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.726528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-02T01:11:41.210193Z","caller":"traceutil/trace.go:171","msg":"trace[1992208164] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2222; }","duration":"120.849413ms","start":"2026-09-02T01:11:41.089322Z","end":"2026-09-02T01:11:41.210172Z","steps":["trace[1992208164] 'agreement among raft nodes before linearized reading' (duration: 120.455294ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:11:41.407967Z","caller":"traceutil/trace.go:171","msg":"trace[1814116113] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"194.090319ms","start":"2026-09-02T01:11:41.213857Z","end":"2026-09-02T01:11:41.407947Z","steps":["trace[1814116113] 'process raft request' (duration: 98.999063ms)","trace[1814116113] 'compare' (duration: 94.944639ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:11:41.408176Z","caller":"traceutil/trace.go:171","msg":"trace[369893920] linearizableReadLoop","detail":"{readStateIndex:2310; appliedIndex:2308; }","duration":"132.047166ms","start":"2026-09-02T01:11:41.276101Z","end":"2026-09-02T01:11:41.408148Z","steps":["trace[369893920] 'read index received' (duration: 36.76564ms)","trace[369893920] 'applied index is now lower than readState.Index' (duration: 95.279756ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:11:41.408254Z","caller":"traceutil/trace.go:171","msg":"trace[269347339] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"193.747712ms","start":"2026-09-02T01:11:41.214469Z","end":"2026-09-02T01:11:41.408217Z","steps":["trace[269347339] 'process raft request' (duration: 193.435467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:11:41.408284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.171531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:11:41.408428Z","caller":"traceutil/trace.go:171","msg":"trace[1053665433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2224; }","duration":"132.326308ms","start":"2026-09-02T01:11:41.276064Z","end":"2026-09-02T01:11:41.40839Z","steps":["trace[1053665433] 'agreement among raft nodes before linearized reading' (duration: 132.171391ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:11:41.464243Z","caller":"traceutil/trace.go:171","msg":"trace[118746522] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"107.512148ms","start":"2026-09-02T01:11:41.35671Z","end":"2026-09-02T01:11:41.464223Z","steps":["trace[118746522] 'process raft request' (duration: 107.092658ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:12:32.821974Z","caller":"traceutil/trace.go:171","msg":"trace[1288421721] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"156.481192ms","start":"2026-09-02T01:12:32.665469Z","end":"2026-09-02T01:12:32.82195Z","steps":["trace[1288421721] 'process raft request' (duration: 156.318646ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:13:08.635003Z","caller":"traceutil/trace.go:171","msg":"trace[1603371859] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"147.39552ms","start":"2026-09-02T01:13:08.487539Z","end":"2026-09-02T01:13:08.634934Z","steps":["trace[1603371859] 'process raft request' (duration: 143.587936ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:15:04.385693Z","caller":"traceutil/trace.go:171","msg":"trace[1406134605] linearizableReadLoop","detail":"{readStateIndex:3533; appliedIndex:3532; }","duration":"103.205358ms","start":"2026-09-02T01:15:04.282464Z","end":"2026-09-02T01:15:04.385669Z","steps":["trace[1406134605] 'read index received' (duration: 79.277899ms)","trace[1406134605] 'applied index is now lower than readState.Index' (duration: 23.926099ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:15:04.385769Z","caller":"traceutil/trace.go:171","msg":"trace[1822980674] transaction","detail":"{read_only:false; response_revision:3403; number_of_response:1; }","duration":"103.469323ms","start":"2026-09-02T01:15:04.282251Z","end":"2026-09-02T01:15:04.385721Z","steps":["trace[1822980674] 'process raft request' (duration: 79.563455ms)","trace[1822980674] 'compare' (duration: 23.680923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:15:04.38586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.385182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:15:04.385906Z","caller":"traceutil/trace.go:171","msg":"trace[1674149645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3403; }","duration":"103.801523ms","start":"2026-09-02T01:15:04.282089Z","end":"2026-09-02T01:15:04.385891Z","steps":["trace[1674149645] 'agreement among raft nodes before linearized reading' (duration: 103.691329ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:17:42.060033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.306181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:17:42.060091Z","caller":"traceutil/trace.go:171","msg":"trace[893972748] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:4912; }","duration":"101.385743ms","start":"2026-09-02T01:17:41.958691Z","end":"2026-09-02T01:17:42.060077Z","steps":["trace[893972748] 'count revisions from in-memory index tree' (duration: 101.24631ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:17:46.759746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.019021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4021f280-501a-41b2-83ee-305d58b1f431\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-02T01:17:46.759877Z","caller":"traceutil/trace.go:171","msg":"trace[107059987] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4021f280-501a-41b2-83ee-305d58b1f431; range_end:; response_count:1; response_revision:5006; }","duration":"157.211216ms","start":"2026-09-02T01:17:46.602623Z","end":"2026-09-02T01:17:46.759834Z","steps":["trace[107059987] 'range keys from in-memory index tree' (duration: 156.848308ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:17:48.981215Z","caller":"traceutil/trace.go:171","msg":"trace[1995351294] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"195.489295ms","start":"2026-09-02T01:17:48.785689Z","end":"2026-09-02T01:17:48.981178Z","steps":["trace[1995351294] 'process raft request' (duration: 175.708071ms)","trace[1995351294] 'compare' (duration: 19.340116ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:17:49.108119Z","caller":"traceutil/trace.go:171","msg":"trace[1529289244] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"202.393791ms","start":"2026-09-02T01:17:48.905699Z","end":"2026-09-02T01:17:49.108093Z","steps":["trace[1529289244] 'process raft request' (duration: 202.311799ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:17:49.108326Z","caller":"traceutil/trace.go:171","msg":"trace[1430010803] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"178.014322ms","start":"2026-09-02T01:17:48.930289Z","end":"2026-09-02T01:17:49.108303Z","steps":["trace[1430010803] 'process raft request' (duration: 177.774738ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:17:49.108119Z","caller":"traceutil/trace.go:171","msg":"trace[223278145] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"321.496661ms","start":"2026-09-02T01:17:48.786567Z","end":"2026-09-02T01:17:49.108064Z","steps":["trace[223278145] 'process raft request' (duration: 308.475782ms)","trace[223278145] 'compare' (duration: 12.818145ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:17:49.108495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:17:48.786548Z","time spent":"321.860817ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":991,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:17:52.296455Z","caller":"traceutil/trace.go:171","msg":"trace[194303469] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"129.581446ms","start":"2026-09-02T01:17:52.166852Z","end":"2026-09-02T01:17:52.296434Z","steps":["trace[194303469] 'process raft request' (duration: 129.186569ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:17:52.298033Z","caller":"traceutil/trace.go:171","msg":"trace[1786879440] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"105.930691ms","start":"2026-09-02T01:17:52.192059Z","end":"2026-09-02T01:17:52.29799Z","steps":["trace[1786879440] 'process raft request' (duration: 105.790969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:02.583856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.953976ms","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-02T01:18:02.583932Z","caller":"traceutil/trace.go:171","msg":"trace[2061978390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5209; }","duration":"186.080308ms","start":"2026-09-02T01:18:02.397834Z","end":"2026-09-02T01:18:02.583914Z","steps":["trace[2061978390] 'range keys from in-memory index tree' (duration: 185.750452ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:02.584188Z","caller":"traceutil/trace.go:171","msg":"trace[424073326] transaction","detail":"{read_only:false; response_revision:5210; number_of_response:1; }","duration":"154.675667ms","start":"2026-09-02T01:18:02.429488Z","end":"2026-09-02T01:18:02.584164Z","steps":["trace[424073326] 'process raft request' (duration: 105.951679ms)","trace[424073326] 'compare' (duration: 48.596506ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:02.772586Z","caller":"traceutil/trace.go:171","msg":"trace[1998405076] linearizableReadLoop","detail":"{readStateIndex:5410; appliedIndex:5409; }","duration":"117.961337ms","start":"2026-09-02T01:18:02.654597Z","end":"2026-09-02T01:18:02.772559Z","steps":["trace[1998405076] 'read index received' (duration: 106.408357ms)","trace[1998405076] 'applied index is now lower than readState.Index' (duration: 11.55174ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:02.772666Z","caller":"traceutil/trace.go:171","msg":"trace[664432377] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"120.410363ms","start":"2026-09-02T01:18:02.652215Z","end":"2026-09-02T01:18:02.772625Z","steps":["trace[664432377] 'process raft request' (duration: 108.70746ms)","trace[664432377] 'compare' (duration: 11.490459ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:18:02.772872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.235503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-09-02T01:18:02.772925Z","caller":"traceutil/trace.go:171","msg":"trace[1325158867] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5212; }","duration":"118.331705ms","start":"2026-09-02T01:18:02.654582Z","end":"2026-09-02T01:18:02.772913Z","steps":["trace[1325158867] 'agreement among raft nodes before linearized reading' (duration: 118.11567ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:02.906077Z","caller":"traceutil/trace.go:171","msg":"trace[1712055548] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"105.725965ms","start":"2026-09-02T01:18:02.80033Z","end":"2026-09-02T01:18:02.906056Z","steps":["trace[1712055548] 'process raft request' (duration: 105.171704ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:22.972089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2878} {"level":"info","ts":"2026-09-02T01:18:23.050993Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2878,"took":"77.642016ms","hash":1467431196,"current-db-size-bytes":53010432,"current-db-size":"53 MB","current-db-size-in-use-bytes":27803648,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-02T01:18:23.051044Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1467431196,"revision":2878,"compact-revision":-1} {"level":"info","ts":"2026-09-02T01:18:45.286214Z","caller":"traceutil/trace.go:171","msg":"trace[1172694340] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"128.839019ms","start":"2026-09-02T01:18:45.157328Z","end":"2026-09-02T01:18:45.286167Z","steps":["trace[1172694340] 'process raft request' (duration: 128.700076ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:20:39.464246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.953733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:20:39.464327Z","caller":"traceutil/trace.go:171","msg":"trace[18649645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6300; }","duration":"184.086038ms","start":"2026-09-02T01:20:39.280224Z","end":"2026-09-02T01:20:39.46431Z","steps":["trace[18649645] 'range keys from in-memory index tree' (duration: 183.863258ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:21:49.006784Z","caller":"traceutil/trace.go:171","msg":"trace[600625668] transaction","detail":"{read_only:false; response_revision:6855; number_of_response:1; }","duration":"111.840212ms","start":"2026-09-02T01:21:48.894916Z","end":"2026-09-02T01:21:49.006756Z","steps":["trace[600625668] 'process raft request' (duration: 31.720921ms)","trace[600625668] 'compare' (duration: 79.993396ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:21:49.01177Z","caller":"traceutil/trace.go:171","msg":"trace[780283529] transaction","detail":"{read_only:false; response_revision:6856; number_of_response:1; }","duration":"115.442974ms","start":"2026-09-02T01:21:48.896309Z","end":"2026-09-02T01:21:49.011752Z","steps":["trace[780283529] 'process raft request' (duration: 115.320129ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:23:20.482375Z","caller":"traceutil/trace.go:171","msg":"trace[433002208] linearizableReadLoop","detail":"{readStateIndex:7671; appliedIndex:7670; }","duration":"130.901478ms","start":"2026-09-02T01:23:20.351457Z","end":"2026-09-02T01:23:20.482359Z","steps":["trace[433002208] 'read index received' (duration: 130.696191ms)","trace[433002208] 'applied index is now lower than readState.Index' (duration: 204.567µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:23:20.482594Z","caller":"traceutil/trace.go:171","msg":"trace[2109918271] transaction","detail":"{read_only:false; response_revision:7397; number_of_response:1; }","duration":"134.403017ms","start":"2026-09-02T01:23:20.348143Z","end":"2026-09-02T01:23:20.482546Z","steps":["trace[2109918271] 'process raft request' (duration: 134.120029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:23:20.48264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.174466ms","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-02T01:23:20.482959Z","caller":"traceutil/trace.go:171","msg":"trace[345137751] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7397; }","duration":"131.538668ms","start":"2026-09-02T01:23:20.351412Z","end":"2026-09-02T01:23:20.48295Z","steps":["trace[345137751] 'agreement among raft nodes before linearized reading' (duration: 131.185377ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:23:22.979225Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5362} {"level":"info","ts":"2026-09-02T01:23:23.132521Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5362,"took":"151.802226ms","hash":3579666553,"current-db-size-bytes":53010432,"current-db-size":"53 MB","current-db-size-in-use-bytes":18829312,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-02T01:23:23.132586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3579666553,"revision":5362,"compact-revision":2878}