{"level":"warn","ts":"2026-10-04T05:43:17.674742Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:43:17.674826Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.138: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.138:2380","--initial-cluster=instance=https://162.253.55.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.138: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-10-04T05:43:17.674883Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T05:43:17.674896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-10-04T05:43:17.674913Z","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-10-04T05:43:17.675553Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"]} {"level":"info","ts":"2026-10-04T05:43:17.675629Z","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.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.138: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-10-04T05:43:17.680424Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.601274ms"} {"level":"info","ts":"2026-10-04T05:43:17.691669Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ee8e5a779fc25cea","cluster-id":"8f8e3b77da0cbcef"} {"level":"info","ts":"2026-10-04T05:43:17.691779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T05:43:17.691811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 0"} {"level":"info","ts":"2026-10-04T05:43:17.691829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ee8e5a779fc25cea [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T05:43:17.691845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 1"} {"level":"info","ts":"2026-10-04T05:43:17.691892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"warn","ts":"2026-10-04T05:43:17.698674Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T05:43:17.702225Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T05:43:17.704161Z","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-10-04T05:43:17.707327Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ee8e5a779fc25cea","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T05:43:17.707545Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ee8e5a779fc25cea","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T05:43:17.708441Z","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-10-04T05:43:17.708621Z","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-10-04T05:43:17.708634Z","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-10-04T05:43:17.709171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"info","ts":"2026-10-04T05:43:17.709327Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","added-peer-id":"ee8e5a779fc25cea","added-peer-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-10-04T05:43:17.714011Z","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-10-04T05:43:17.714233Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ee8e5a779fc25cea","initial-advertise-peer-urls":["https://162.253.55.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T05:43:17.714279Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T05:43:17.714351Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-10-04T05:43:17.714365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-10-04T05:43:18.592688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T05:43:18.5928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T05:43:18.592829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgPreVoteResp from ee8e5a779fc25cea at term 1"} {"level":"info","ts":"2026-10-04T05:43:18.592851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became candidate at term 2"} {"level":"info","ts":"2026-10-04T05:43:18.592862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgVoteResp from ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-10-04T05:43:18.592878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became leader at term 2"} {"level":"info","ts":"2026-10-04T05:43:18.592897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ee8e5a779fc25cea elected leader ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-10-04T05:43:18.595081Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:18.596333Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:43:18.596324Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ee8e5a779fc25cea","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.138:2379]}","request-path":"/0/members/ee8e5a779fc25cea/attributes","cluster-id":"8f8e3b77da0cbcef","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T05:43:18.596373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T05:43:18.596722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T05:43:18.596816Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T05:43:18.597024Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:18.597162Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:18.5972Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T05:43:18.603424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T05:43:18.620167Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.138:2379"} {"level":"warn","ts":"2026-10-04T05:43:22.035759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.026999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:43:22.036137Z","caller":"traceutil/trace.go:171","msg":"trace[428269653] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"460.484008ms","start":"2026-10-04T05:43:21.575593Z","end":"2026-10-04T05:43:22.036077Z","steps":["trace[428269653] 'process raft request' (duration: 186.445953ms)","trace[428269653] 'compare' (duration: 272.768445ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:43:22.036435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:43:21.575569Z","time spent":"460.711482ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":732,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:43:22.419015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.747519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:43:22.419186Z","caller":"traceutil/trace.go:171","msg":"trace[1768902235] linearizableReadLoop","detail":"{readStateIndex:156; appliedIndex:155; }","duration":"352.397662ms","start":"2026-10-04T05:43:22.066766Z","end":"2026-10-04T05:43:22.419163Z","steps":["trace[1768902235] 'read index received' (duration: 154.369488ms)","trace[1768902235] 'applied index is now lower than readState.Index' (duration: 198.027114ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:43:22.419346Z","caller":"traceutil/trace.go:171","msg":"trace[252674626] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"377.275326ms","start":"2026-10-04T05:43:22.042056Z","end":"2026-10-04T05:43:22.419331Z","steps":["trace[252674626] 'process raft request' (duration: 179.134111ms)","trace[252674626] 'compare' (duration: 197.595337ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:43:22.419468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:43:22.042029Z","time spent":"377.385578ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:43:22.419783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.159674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-10-04T05:43:22.419962Z","caller":"traceutil/trace.go:171","msg":"trace[2056138410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:152; }","duration":"302.368988ms","start":"2026-10-04T05:43:22.117567Z","end":"2026-10-04T05:43:22.419936Z","steps":["trace[2056138410] 'agreement among raft nodes before linearized reading' (duration: 302.086303ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:22.419782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.996031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:43:22.420161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:43:22.117547Z","time spent":"302.54285ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-04T05:43:22.420121Z","caller":"traceutil/trace.go:171","msg":"trace[1768108752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:152; }","duration":"353.408937ms","start":"2026-10-04T05:43:22.066692Z","end":"2026-10-04T05:43:22.420101Z","steps":["trace[1768108752] 'agreement among raft nodes before linearized reading' (duration: 352.976671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:43:22.4205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:43:22.066668Z","time spent":"353.758872ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-04T05:43:54.953229Z","caller":"traceutil/trace.go:171","msg":"trace[985344816] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"112.008504ms","start":"2026-10-04T05:43:54.841193Z","end":"2026-10-04T05:43:54.953201Z","steps":["trace[985344816] 'process raft request' (duration: 111.872112ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:01.875117Z","caller":"traceutil/trace.go:171","msg":"trace[962542157] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"200.892818ms","start":"2026-10-04T05:44:01.674199Z","end":"2026-10-04T05:44:01.875092Z","steps":["trace[962542157] 'read index received' (duration: 200.054557ms)","trace[962542157] 'applied index is now lower than readState.Index' (duration: 837.291µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:01.875183Z","caller":"traceutil/trace.go:171","msg":"trace[690526321] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"219.245017ms","start":"2026-10-04T05:44:01.655905Z","end":"2026-10-04T05:44:01.87515Z","steps":["trace[690526321] 'process raft request' (duration: 218.611729ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:01.875374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.163571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wr4px\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-04T05:44:01.875413Z","caller":"traceutil/trace.go:171","msg":"trace[1911646156] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wr4px; range_end:; response_count:1; response_revision:590; }","duration":"201.250312ms","start":"2026-10-04T05:44:01.674152Z","end":"2026-10-04T05:44:01.875402Z","steps":["trace[1911646156] 'agreement among raft nodes before linearized reading' (duration: 201.038069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:02.68022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.064576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:44:02.680365Z","caller":"traceutil/trace.go:171","msg":"trace[843882553] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"784.983541ms","start":"2026-10-04T05:44:01.895359Z","end":"2026-10-04T05:44:02.680342Z","steps":["trace[843882553] 'process raft request' (duration: 489.025483ms)","trace[843882553] 'compare' (duration: 294.892324ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:02.680437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:01.895335Z","time spent":"785.065492ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:44:02.680713Z","caller":"traceutil/trace.go:171","msg":"trace[1289732648] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"230.083559ms","start":"2026-10-04T05:44:02.450608Z","end":"2026-10-04T05:44:02.680691Z","steps":["trace[1289732648] 'read index received' (duration: 230.003198ms)","trace[1289732648] 'applied index is now lower than readState.Index' (duration: 79.391µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:02.680906Z","caller":"traceutil/trace.go:171","msg":"trace[530240000] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"730.240348ms","start":"2026-10-04T05:44:01.950585Z","end":"2026-10-04T05:44:02.680825Z","steps":["trace[530240000] 'process raft request' (duration: 730.013815ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:02.681027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.716042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:44:02.681077Z","caller":"traceutil/trace.go:171","msg":"trace[836396347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"228.824384ms","start":"2026-10-04T05:44:02.45224Z","end":"2026-10-04T05:44:02.681064Z","steps":["trace[836396347] 'agreement among raft nodes before linearized reading' (duration: 228.669712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:02.681094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.478714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-04T05:44:02.68119Z","caller":"traceutil/trace.go:171","msg":"trace[348865315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"230.647716ms","start":"2026-10-04T05:44:02.450525Z","end":"2026-10-04T05:44:02.681173Z","steps":["trace[348865315] 'agreement among raft nodes before linearized reading' (duration: 230.327172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:02.681284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.690597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:44:02.681342Z","caller":"traceutil/trace.go:171","msg":"trace[91547637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"148.762779ms","start":"2026-10-04T05:44:02.532559Z","end":"2026-10-04T05:44:02.681322Z","steps":["trace[91547637] 'agreement among raft nodes before linearized reading' (duration: 148.690148ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:02.681129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:44:01.95054Z","time spent":"730.442551ms","remote":"127.0.0.1:49212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-04T05:44:03.970298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.280801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:44:03.970537Z","caller":"traceutil/trace.go:171","msg":"trace[661972905] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"144.312734ms","start":"2026-10-04T05:44:03.826203Z","end":"2026-10-04T05:44:03.970516Z","steps":["trace[661972905] 'compare' (duration: 133.984347ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:05.016205Z","caller":"traceutil/trace.go:171","msg":"trace[966378362] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"114.391188ms","start":"2026-10-04T05:44:04.901784Z","end":"2026-10-04T05:44:05.016175Z","steps":["trace[966378362] 'process raft request' (duration: 114.184375ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:19.037811Z","caller":"traceutil/trace.go:171","msg":"trace[1689581438] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"244.148874ms","start":"2026-10-04T05:44:18.793641Z","end":"2026-10-04T05:44:19.037789Z","steps":["trace[1689581438] 'read index received' (duration: 243.873371ms)","trace[1689581438] 'applied index is now lower than readState.Index' (duration: 264.073µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:44:19.037943Z","caller":"traceutil/trace.go:171","msg":"trace[363357397] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"244.516288ms","start":"2026-10-04T05:44:18.793386Z","end":"2026-10-04T05:44:19.037902Z","steps":["trace[363357397] 'process raft request' (duration: 244.190585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:19.038136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.120651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-10-04T05:44:19.038135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.112424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-04T05:44:19.038182Z","caller":"traceutil/trace.go:171","msg":"trace[561771519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"230.197402ms","start":"2026-10-04T05:44:18.807975Z","end":"2026-10-04T05:44:19.038172Z","steps":["trace[561771519] 'agreement among raft nodes before linearized reading' (duration: 230.08299ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:19.038225Z","caller":"traceutil/trace.go:171","msg":"trace[1947371165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"238.281946ms","start":"2026-10-04T05:44:18.799928Z","end":"2026-10-04T05:44:19.03821Z","steps":["trace[1947371165] 'agreement among raft nodes before linearized reading' (duration: 238.054703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:19.038845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.178996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:44:19.038937Z","caller":"traceutil/trace.go:171","msg":"trace[2034517765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"245.290927ms","start":"2026-10-04T05:44:18.793619Z","end":"2026-10-04T05:44:19.03891Z","steps":["trace[2034517765] 'agreement among raft nodes before linearized reading' (duration: 244.284275ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:51.415093Z","caller":"traceutil/trace.go:171","msg":"trace[34779613] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"134.167667ms","start":"2026-10-04T05:44:51.280901Z","end":"2026-10-04T05:44:51.415068Z","steps":["trace[34779613] 'process raft request' (duration: 133.985505ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:51.438464Z","caller":"traceutil/trace.go:171","msg":"trace[1591486207] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1204; }","duration":"155.471222ms","start":"2026-10-04T05:44:51.282968Z","end":"2026-10-04T05:44:51.438439Z","steps":["trace[1591486207] 'read index received' (duration: 131.868233ms)","trace[1591486207] 'applied index is now lower than readState.Index' (duration: 23.602079ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:44:51.438679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.694135ms","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-10-04T05:44:51.438718Z","caller":"traceutil/trace.go:171","msg":"trace[2082443599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1166; }","duration":"155.787055ms","start":"2026-10-04T05:44:51.28292Z","end":"2026-10-04T05:44:51.438707Z","steps":["trace[2082443599] 'agreement among raft nodes before linearized reading' (duration: 155.640154ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:44:51.438796Z","caller":"traceutil/trace.go:171","msg":"trace[507170517] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"156.266081ms","start":"2026-10-04T05:44:51.282499Z","end":"2026-10-04T05:44:51.438765Z","steps":["trace[507170517] 'process raft request' (duration: 155.841436ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:44:51.438862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.002744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-04T05:44:51.439026Z","caller":"traceutil/trace.go:171","msg":"trace[671892950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1166; }","duration":"149.205716ms","start":"2026-10-04T05:44:51.289805Z","end":"2026-10-04T05:44:51.439011Z","steps":["trace[671892950] 'agreement among raft nodes before linearized reading' (duration: 148.806292ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:06.11819Z","caller":"traceutil/trace.go:171","msg":"trace[1491756982] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"111.837004ms","start":"2026-10-04T05:45:06.006332Z","end":"2026-10-04T05:45:06.118169Z","steps":["trace[1491756982] 'process raft request' (duration: 111.669972ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:37.590447Z","caller":"traceutil/trace.go:171","msg":"trace[1762479349] linearizableReadLoop","detail":"{readStateIndex:1881; appliedIndex:1880; }","duration":"256.006362ms","start":"2026-10-04T05:45:37.334419Z","end":"2026-10-04T05:45:37.590425Z","steps":["trace[1762479349] 'read index received' (duration: 255.867951ms)","trace[1762479349] 'applied index is now lower than readState.Index' (duration: 137.561µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:45:37.590874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.430796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e42b90c1-f2c0-4ed9-8f39-5dbdd39c7a99\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-10-04T05:45:37.590978Z","caller":"traceutil/trace.go:171","msg":"trace[1473840270] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e42b90c1-f2c0-4ed9-8f39-5dbdd39c7a99; range_end:; response_count:1; response_revision:1821; }","duration":"256.594799ms","start":"2026-10-04T05:45:37.33436Z","end":"2026-10-04T05:45:37.590955Z","steps":["trace[1473840270] 'agreement among raft nodes before linearized reading' (duration: 256.409787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:37.591016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.121338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T05:45:37.591109Z","caller":"traceutil/trace.go:171","msg":"trace[369027472] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1821; }","duration":"128.269779ms","start":"2026-10-04T05:45:37.462821Z","end":"2026-10-04T05:45:37.591091Z","steps":["trace[369027472] 'agreement among raft nodes before linearized reading' (duration: 128.023927ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:37.590988Z","caller":"traceutil/trace.go:171","msg":"trace[66304338] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"429.645784ms","start":"2026-10-04T05:45:37.161308Z","end":"2026-10-04T05:45:37.590954Z","steps":["trace[66304338] 'process raft request' (duration: 428.908406ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:45:37.591393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:45:37.16129Z","time spent":"429.983877ms","remote":"127.0.0.1:59166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T05:45:37.781184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.273729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-04T05:45:37.781229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.471882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-04T05:45:37.781268Z","caller":"traceutil/trace.go:171","msg":"trace[1716974533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1821; }","duration":"125.420431ms","start":"2026-10-04T05:45:37.655831Z","end":"2026-10-04T05:45:37.781252Z","steps":["trace[1716974533] 'range keys from in-memory index tree' (duration: 125.076457ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:37.781277Z","caller":"traceutil/trace.go:171","msg":"trace[431886866] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1821; }","duration":"125.560072ms","start":"2026-10-04T05:45:37.655704Z","end":"2026-10-04T05:45:37.781264Z","steps":["trace[431886866] 'range keys from in-memory index tree' (duration: 125.33304ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:37.903255Z","caller":"traceutil/trace.go:171","msg":"trace[493951929] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"117.271762ms","start":"2026-10-04T05:45:37.785968Z","end":"2026-10-04T05:45:37.903239Z","steps":["trace[493951929] 'process raft request' (duration: 117.193891ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:37.90325Z","caller":"traceutil/trace.go:171","msg":"trace[1238409312] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"117.300852ms","start":"2026-10-04T05:45:37.785922Z","end":"2026-10-04T05:45:37.903223Z","steps":["trace[1238409312] 'process raft request' (duration: 101.528818ms)","trace[1238409312] 'compare' (duration: 15.619922ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:45:37.903561Z","caller":"traceutil/trace.go:171","msg":"trace[1638368032] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"115.178131ms","start":"2026-10-04T05:45:37.788359Z","end":"2026-10-04T05:45:37.903537Z","steps":["trace[1638368032] 'process raft request' (duration: 115.08951ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:37.903593Z","caller":"traceutil/trace.go:171","msg":"trace[1696309300] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"117.210071ms","start":"2026-10-04T05:45:37.786361Z","end":"2026-10-04T05:45:37.903571Z","steps":["trace[1696309300] 'process raft request' (duration: 116.844107ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:45:42.715193Z","caller":"traceutil/trace.go:171","msg":"trace[660255484] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"132.089191ms","start":"2026-10-04T05:45:42.583071Z","end":"2026-10-04T05:45:42.71516Z","steps":["trace[660255484] 'process raft request' (duration: 131.903953ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:09.012503Z","caller":"traceutil/trace.go:171","msg":"trace[1294033183] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"141.079468ms","start":"2026-10-04T05:46:08.871402Z","end":"2026-10-04T05:46:09.012482Z","steps":["trace[1294033183] 'process raft request' (duration: 140.932343ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:46:09.443403Z","caller":"traceutil/trace.go:171","msg":"trace[778085547] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"255.475463ms","start":"2026-10-04T05:46:09.18791Z","end":"2026-10-04T05:46:09.443386Z","steps":["trace[778085547] 'process raft request' (duration: 255.363829ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:47:05.241615Z","caller":"traceutil/trace.go:171","msg":"trace[22175182] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"182.029311ms","start":"2026-10-04T05:47:05.059569Z","end":"2026-10-04T05:47:05.241599Z","steps":["trace[22175182] 'process raft request' (duration: 181.902528ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:47:26.577511Z","caller":"traceutil/trace.go:171","msg":"trace[735997626] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"118.348151ms","start":"2026-10-04T05:47:26.459135Z","end":"2026-10-04T05:47:26.577483Z","steps":["trace[735997626] 'process raft request' (duration: 76.271911ms)","trace[735997626] 'compare' (duration: 41.806823ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:47:32.58861Z","caller":"traceutil/trace.go:171","msg":"trace[163908509] linearizableReadLoop","detail":"{readStateIndex:2869; appliedIndex:2868; }","duration":"137.271172ms","start":"2026-10-04T05:47:32.451312Z","end":"2026-10-04T05:47:32.588583Z","steps":["trace[163908509] 'read index received' (duration: 137.100158ms)","trace[163908509] 'applied index is now lower than readState.Index' (duration: 169.774µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:47:32.588663Z","caller":"traceutil/trace.go:171","msg":"trace[439012271] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"150.172124ms","start":"2026-10-04T05:47:32.43846Z","end":"2026-10-04T05:47:32.588632Z","steps":["trace[439012271] 'process raft request' (duration: 149.961369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:47:32.58892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.84787ms","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-10-04T05:47:32.588966Z","caller":"traceutil/trace.go:171","msg":"trace[140128737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2759; }","duration":"101.930371ms","start":"2026-10-04T05:47:32.487023Z","end":"2026-10-04T05:47:32.588954Z","steps":["trace[140128737] 'agreement among raft nodes before linearized reading' (duration: 101.737387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:47:32.589016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.65133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-10-04T05:47:32.589164Z","caller":"traceutil/trace.go:171","msg":"trace[2144851174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2759; }","duration":"137.872985ms","start":"2026-10-04T05:47:32.451274Z","end":"2026-10-04T05:47:32.589147Z","steps":["trace[2144851174] 'agreement among raft nodes before linearized reading' (duration: 137.449185ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:47:32.757595Z","caller":"traceutil/trace.go:171","msg":"trace[1258873039] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"164.597622ms","start":"2026-10-04T05:47:32.592942Z","end":"2026-10-04T05:47:32.75754Z","steps":["trace[1258873039] 'process raft request' (duration: 126.389286ms)","trace[1258873039] 'compare' (duration: 37.894389ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:47:32.963667Z","caller":"traceutil/trace.go:171","msg":"trace[1913130413] linearizableReadLoop","detail":"{readStateIndex:2871; appliedIndex:2869; }","duration":"368.383993ms","start":"2026-10-04T05:47:32.595262Z","end":"2026-10-04T05:47:32.963646Z","steps":["trace[1913130413] 'read index received' (duration: 124.083781ms)","trace[1913130413] 'applied index is now lower than readState.Index' (duration: 244.299292ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:47:32.963725Z","caller":"traceutil/trace.go:171","msg":"trace[1870341188] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"369.819358ms","start":"2026-10-04T05:47:32.593875Z","end":"2026-10-04T05:47:32.963694Z","steps":["trace[1870341188] 'process raft request' (duration: 364.945794ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:47:32.963802Z","caller":"traceutil/trace.go:171","msg":"trace[1375947346] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"269.980385ms","start":"2026-10-04T05:47:32.693807Z","end":"2026-10-04T05:47:32.963788Z","steps":["trace[1375947346] 'process raft request' (duration: 269.663337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:47:32.963879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:47:32.593853Z","time spent":"369.933461ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:47:32.963885Z","caller":"traceutil/trace.go:171","msg":"trace[1047265081] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"249.533805ms","start":"2026-10-04T05:47:32.71431Z","end":"2026-10-04T05:47:32.963844Z","steps":["trace[1047265081] 'process raft request' (duration: 249.287489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:47:32.964097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.283788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-10-04T05:47:32.964153Z","caller":"traceutil/trace.go:171","msg":"trace[1463878239] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2763; }","duration":"113.38951ms","start":"2026-10-04T05:47:32.850748Z","end":"2026-10-04T05:47:32.964137Z","steps":["trace[1463878239] 'agreement among raft nodes before linearized reading' (duration: 113.192545ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:47:32.964268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.167611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.138\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-10-04T05:47:32.964318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.989448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-10-04T05:47:32.964348Z","caller":"traceutil/trace.go:171","msg":"trace[1297047112] range","detail":"{range_begin:/registry/masterleases/162.253.55.138; range_end:; response_count:1; response_revision:2763; }","duration":"184.296374ms","start":"2026-10-04T05:47:32.780033Z","end":"2026-10-04T05:47:32.964329Z","steps":["trace[1297047112] 'agreement among raft nodes before linearized reading' (duration: 183.861824ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:47:32.964367Z","caller":"traceutil/trace.go:171","msg":"trace[1042283654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2763; }","duration":"369.134631ms","start":"2026-10-04T05:47:32.595219Z","end":"2026-10-04T05:47:32.964354Z","steps":["trace[1042283654] 'agreement among raft nodes before linearized reading' (duration: 368.847854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:47:32.964401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:47:32.595208Z","time spent":"369.185553ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-04T05:49:53.581619Z","caller":"traceutil/trace.go:171","msg":"trace[729765105] transaction","detail":"{read_only:false; response_revision:4356; number_of_response:1; }","duration":"107.637891ms","start":"2026-10-04T05:49:53.473953Z","end":"2026-10-04T05:49:53.581591Z","steps":["trace[729765105] 'process raft request' (duration: 99.908527ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:49:53.581531Z","caller":"traceutil/trace.go:171","msg":"trace[1118123605] linearizableReadLoop","detail":"{readStateIndex:4547; appliedIndex:4546; }","duration":"106.191388ms","start":"2026-10-04T05:49:53.475321Z","end":"2026-10-04T05:49:53.581512Z","steps":["trace[1118123605] 'read index received' (duration: 55.467797ms)","trace[1118123605] 'applied index is now lower than readState.Index' (duration: 50.722781ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:49:53.581713Z","caller":"traceutil/trace.go:171","msg":"trace[1699070723] transaction","detail":"{read_only:false; response_revision:4357; number_of_response:1; }","duration":"104.095585ms","start":"2026-10-04T05:49:53.477595Z","end":"2026-10-04T05:49:53.58169Z","steps":["trace[1699070723] 'process raft request' (duration: 103.845001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:49:53.582019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.681566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4737"} {"level":"info","ts":"2026-10-04T05:49:53.582077Z","caller":"traceutil/trace.go:171","msg":"trace[2020100492] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4357; }","duration":"106.776818ms","start":"2026-10-04T05:49:53.475288Z","end":"2026-10-04T05:49:53.582065Z","steps":["trace[2020100492] 'agreement among raft nodes before linearized reading' (duration: 106.601975ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:01.503697Z","caller":"traceutil/trace.go:171","msg":"trace[319540533] transaction","detail":"{read_only:false; response_revision:4517; number_of_response:1; }","duration":"104.791412ms","start":"2026-10-04T05:50:01.398876Z","end":"2026-10-04T05:50:01.503668Z","steps":["trace[319540533] 'process raft request' (duration: 66.500368ms)","trace[319540533] 'compare' (duration: 38.179502ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:05.876362Z","caller":"traceutil/trace.go:171","msg":"trace[271081992] linearizableReadLoop","detail":"{readStateIndex:4793; appliedIndex:4792; }","duration":"160.582324ms","start":"2026-10-04T05:50:05.715756Z","end":"2026-10-04T05:50:05.876338Z","steps":["trace[271081992] 'read index received' (duration: 141.825631ms)","trace[271081992] 'applied index is now lower than readState.Index' (duration: 18.755433ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:05.877127Z","caller":"traceutil/trace.go:171","msg":"trace[1774992102] transaction","detail":"{read_only:false; response_revision:4594; number_of_response:1; }","duration":"163.098984ms","start":"2026-10-04T05:50:05.713987Z","end":"2026-10-04T05:50:05.877086Z","steps":["trace[1774992102] 'process raft request' (duration: 143.6465ms)","trace[1774992102] 'compare' (duration: 18.593941ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:05.877141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.358167ms","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-10-04T05:50:05.87714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.623929ms","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-10-04T05:50:05.877215Z","caller":"traceutil/trace.go:171","msg":"trace[128348443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4594; }","duration":"161.468379ms","start":"2026-10-04T05:50:05.715728Z","end":"2026-10-04T05:50:05.877196Z","steps":["trace[128348443] 'agreement among raft nodes before linearized reading' (duration: 161.181064ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:05.877226Z","caller":"traceutil/trace.go:171","msg":"trace[1787813173] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4594; }","duration":"104.74218ms","start":"2026-10-04T05:50:05.772463Z","end":"2026-10-04T05:50:05.877205Z","steps":["trace[1787813173] 'agreement among raft nodes before linearized reading' (duration: 104.495506ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:10.640742Z","caller":"traceutil/trace.go:171","msg":"trace[594273440] linearizableReadLoop","detail":"{readStateIndex:4873; appliedIndex:4872; }","duration":"110.983603ms","start":"2026-10-04T05:50:10.529735Z","end":"2026-10-04T05:50:10.640718Z","steps":["trace[594273440] 'read index received' (duration: 110.677378ms)","trace[594273440] 'applied index is now lower than readState.Index' (duration: 304.865µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:10.640854Z","caller":"traceutil/trace.go:171","msg":"trace[2018710021] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"120.509462ms","start":"2026-10-04T05:50:10.520317Z","end":"2026-10-04T05:50:10.640827Z","steps":["trace[2018710021] 'process raft request' (duration: 120.135126ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:10.641011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.210256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T05:50:10.641305Z","caller":"traceutil/trace.go:171","msg":"trace[687797703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4669; }","duration":"111.583813ms","start":"2026-10-04T05:50:10.529699Z","end":"2026-10-04T05:50:10.641283Z","steps":["trace[687797703] 'agreement among raft nodes before linearized reading' (duration: 111.199107ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:14.702666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.88006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10516"} {"level":"info","ts":"2026-10-04T05:50:14.702758Z","caller":"traceutil/trace.go:171","msg":"trace[149541575] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4714; }","duration":"155.045333ms","start":"2026-10-04T05:50:14.54769Z","end":"2026-10-04T05:50:14.702735Z","steps":["trace[149541575] 'range keys from in-memory index tree' (duration: 154.645886ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:14.70291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.40773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-10-04T05:50:14.702927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.906313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-10-04T05:50:14.702995Z","caller":"traceutil/trace.go:171","msg":"trace[1456590442] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4714; }","duration":"487.535242ms","start":"2026-10-04T05:50:14.215443Z","end":"2026-10-04T05:50:14.702978Z","steps":["trace[1456590442] 'range keys from in-memory index tree' (duration: 487.240748ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:14.703014Z","caller":"traceutil/trace.go:171","msg":"trace[593222836] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4714; }","duration":"115.033805ms","start":"2026-10-04T05:50:14.587964Z","end":"2026-10-04T05:50:14.702998Z","steps":["trace[593222836] 'range keys from in-memory index tree' (duration: 114.765582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:14.703035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:14.215409Z","time spent":"487.616994ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":549,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-10-04T05:50:14.703163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.631039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-10-04T05:50:14.703238Z","caller":"traceutil/trace.go:171","msg":"trace[307237474] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4714; }","duration":"335.770821ms","start":"2026-10-04T05:50:14.367449Z","end":"2026-10-04T05:50:14.70322Z","steps":["trace[307237474] 'range keys from in-memory index tree' (duration: 335.435806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:14.70328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:14.367419Z","time spent":"335.851502ms","remote":"127.0.0.1:55564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3222,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-10-04T05:50:14.86968Z","caller":"traceutil/trace.go:171","msg":"trace[1056568292] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"153.780123ms","start":"2026-10-04T05:50:14.715878Z","end":"2026-10-04T05:50:14.869658Z","steps":["trace[1056568292] 'process raft request' (duration: 153.695302ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:14.870015Z","caller":"traceutil/trace.go:171","msg":"trace[841001163] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"160.201182ms","start":"2026-10-04T05:50:14.709761Z","end":"2026-10-04T05:50:14.869962Z","steps":["trace[841001163] 'process raft request' (duration: 159.703164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:14.870166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.802419ms","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-10-04T05:50:14.870273Z","caller":"traceutil/trace.go:171","msg":"trace[905505698] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:4717; }","duration":"156.948972ms","start":"2026-10-04T05:50:14.713295Z","end":"2026-10-04T05:50:14.870244Z","steps":["trace[905505698] 'agreement among raft nodes before linearized reading' (duration: 156.641947ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:15.095429Z","caller":"traceutil/trace.go:171","msg":"trace[917365707] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"159.476308ms","start":"2026-10-04T05:50:14.93593Z","end":"2026-10-04T05:50:15.095406Z","steps":["trace[917365707] 'process raft request' (duration: 89.647533ms)","trace[917365707] 'compare' (duration: 69.559971ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:15.113336Z","caller":"traceutil/trace.go:171","msg":"trace[613519845] linearizableReadLoop","detail":"{readStateIndex:4929; appliedIndex:4927; }","duration":"170.697251ms","start":"2026-10-04T05:50:14.942613Z","end":"2026-10-04T05:50:15.113311Z","steps":["trace[613519845] 'read index received' (duration: 82.896509ms)","trace[613519845] 'applied index is now lower than readState.Index' (duration: 87.799512ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:15.113614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.000625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-10-04T05:50:15.113675Z","caller":"traceutil/trace.go:171","msg":"trace[1475327380] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4725; }","duration":"171.128187ms","start":"2026-10-04T05:50:14.942529Z","end":"2026-10-04T05:50:15.113657Z","steps":["trace[1475327380] 'agreement among raft nodes before linearized reading' (duration: 170.878414ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:15.113935Z","caller":"traceutil/trace.go:171","msg":"trace[1887914482] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"169.36058ms","start":"2026-10-04T05:50:14.944561Z","end":"2026-10-04T05:50:15.113921Z","steps":["trace[1887914482] 'process raft request' (duration: 168.68467ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:15.114347Z","caller":"traceutil/trace.go:171","msg":"trace[1771107084] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"173.666057ms","start":"2026-10-04T05:50:14.940621Z","end":"2026-10-04T05:50:15.114287Z","steps":["trace[1771107084] 'process raft request' (duration: 172.470409ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:15.159758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.265527ms","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-10-04T05:50:15.159809Z","caller":"traceutil/trace.go:171","msg":"trace[615412123] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4725; }","duration":"215.338809ms","start":"2026-10-04T05:50:14.944457Z","end":"2026-10-04T05:50:15.159796Z","steps":["trace[615412123] 'agreement among raft nodes before linearized reading' (duration: 214.933943ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:15.565984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.610882ms","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-10-04T05:50:15.566077Z","caller":"traceutil/trace.go:171","msg":"trace[2017563818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4731; }","duration":"141.744893ms","start":"2026-10-04T05:50:15.424292Z","end":"2026-10-04T05:50:15.566037Z","steps":["trace[2017563818] 'range keys from in-memory index tree' (duration: 141.391158ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:50:15.717712Z","caller":"traceutil/trace.go:171","msg":"trace[2026589050] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"151.979951ms","start":"2026-10-04T05:50:15.565705Z","end":"2026-10-04T05:50:15.717685Z","steps":["trace[2026589050] 'process raft request' (duration: 151.813119ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:15.950509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.300222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:50:15.950673Z","caller":"traceutil/trace.go:171","msg":"trace[1444762496] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4937; }","duration":"328.551421ms","start":"2026-10-04T05:50:15.6221Z","end":"2026-10-04T05:50:15.950651Z","steps":["trace[1444762496] 'read index received' (duration: 95.476801ms)","trace[1444762496] 'applied index is now lower than readState.Index' (duration: 233.07285ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:50:15.950685Z","caller":"traceutil/trace.go:171","msg":"trace[32342811] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"380.355499ms","start":"2026-10-04T05:50:15.570303Z","end":"2026-10-04T05:50:15.950658Z","steps":["trace[32342811] 'process raft request' (duration: 252.813734ms)","trace[32342811] 'compare' (duration: 127.067958ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:50:15.950811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.210745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T05:50:15.950825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:15.570286Z","time spent":"380.461481ms","remote":"127.0.0.1:59250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T05:50:15.950842Z","caller":"traceutil/trace.go:171","msg":"trace[2087199047] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4733; }","duration":"313.268786ms","start":"2026-10-04T05:50:15.637562Z","end":"2026-10-04T05:50:15.950831Z","steps":["trace[2087199047] 'agreement among raft nodes before linearized reading' (duration: 313.207725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:15.950874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:15.637549Z","time spent":"313.315367ms","remote":"127.0.0.1:40226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-04T05:50:15.950976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.836416ms","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-10-04T05:50:15.951057Z","caller":"traceutil/trace.go:171","msg":"trace[1509907308] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4733; }","duration":"328.986348ms","start":"2026-10-04T05:50:15.622047Z","end":"2026-10-04T05:50:15.951033Z","steps":["trace[1509907308] 'agreement among raft nodes before linearized reading' (duration: 328.689204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:15.951249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.664338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-04T05:50:15.951297Z","caller":"traceutil/trace.go:171","msg":"trace[1309508157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4733; }","duration":"253.7946ms","start":"2026-10-04T05:50:15.697491Z","end":"2026-10-04T05:50:15.951286Z","steps":["trace[1309508157] 'agreement among raft nodes before linearized reading' (duration: 253.624218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:50:15.951634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T05:50:15.622027Z","time spent":"329.073409ms","remote":"127.0.0.1:59144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-04T05:50:50.152481Z","caller":"traceutil/trace.go:171","msg":"trace[1993262041] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"138.973847ms","start":"2026-10-04T05:50:50.013478Z","end":"2026-10-04T05:50:50.152452Z","steps":["trace[1993262041] 'process raft request' (duration: 138.635242ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:03.136627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.69721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T05:51:03.136737Z","caller":"traceutil/trace.go:171","msg":"trace[455458522] linearizableReadLoop","detail":"{readStateIndex:5428; appliedIndex:5427; }","duration":"253.374179ms","start":"2026-10-04T05:51:02.883343Z","end":"2026-10-04T05:51:03.136717Z","steps":["trace[455458522] 'read index received' (duration: 56.379325ms)","trace[455458522] 'applied index is now lower than readState.Index' (duration: 196.993004ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T05:51:03.136749Z","caller":"traceutil/trace.go:171","msg":"trace[1861455341] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"260.41763ms","start":"2026-10-04T05:51:02.876311Z","end":"2026-10-04T05:51:03.136729Z","steps":["trace[1861455341] 'process raft request' (duration: 63.512458ms)","trace[1861455341] 'compare' (duration: 196.487466ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:51:03.137053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.713623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-db-init-9794j\" ","response":"range_response_count:1 size:7344"} {"level":"info","ts":"2026-10-04T05:51:03.137093Z","caller":"traceutil/trace.go:171","msg":"trace[1484127730] range","detail":"{range_begin:/registry/pods/openstack/keystone-db-init-9794j; range_end:; response_count:1; response_revision:5202; }","duration":"253.783844ms","start":"2026-10-04T05:51:02.8833Z","end":"2026-10-04T05:51:03.137084Z","steps":["trace[1484127730] 'agreement among raft nodes before linearized reading' (duration: 253.48387ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T05:51:03.214363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.11547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-04T05:51:03.214407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.283707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-10-04T05:51:03.214452Z","caller":"traceutil/trace.go:171","msg":"trace[1481209193] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5203; }","duration":"195.357479ms","start":"2026-10-04T05:51:03.019082Z","end":"2026-10-04T05:51:03.21444Z","steps":["trace[1481209193] 'agreement among raft nodes before linearized reading' (duration: 195.170496ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.214416Z","caller":"traceutil/trace.go:171","msg":"trace[1058605316] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5203; }","duration":"295.209951ms","start":"2026-10-04T05:51:02.919193Z","end":"2026-10-04T05:51:03.214403Z","steps":["trace[1058605316] 'agreement among raft nodes before linearized reading' (duration: 295.11183ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.214938Z","caller":"traceutil/trace.go:171","msg":"trace[821571966] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"131.420906ms","start":"2026-10-04T05:51:03.083491Z","end":"2026-10-04T05:51:03.214912Z","steps":["trace[821571966] 'process raft request' (duration: 130.544893ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.338967Z","caller":"traceutil/trace.go:171","msg":"trace[1376783759] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"133.6929ms","start":"2026-10-04T05:51:03.205247Z","end":"2026-10-04T05:51:03.33894Z","steps":["trace[1376783759] 'process raft request' (duration: 133.636229ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.339381Z","caller":"traceutil/trace.go:171","msg":"trace[1489031568] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"191.96177ms","start":"2026-10-04T05:51:03.147408Z","end":"2026-10-04T05:51:03.339369Z","steps":["trace[1489031568] 'process raft request' (duration: 191.339881ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.3401Z","caller":"traceutil/trace.go:171","msg":"trace[599483264] linearizableReadLoop","detail":"{readStateIndex:5431; appliedIndex:5431; }","duration":"125.898197ms","start":"2026-10-04T05:51:03.214188Z","end":"2026-10-04T05:51:03.340087Z","steps":["trace[599483264] 'read index received' (duration: 125.892447ms)","trace[599483264] 'applied index is now lower than readState.Index' (duration: 4.37µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T05:51:03.340257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.423326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-10-04T05:51:03.340292Z","caller":"traceutil/trace.go:171","msg":"trace[1530440544] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5205; }","duration":"201.504907ms","start":"2026-10-04T05:51:03.138778Z","end":"2026-10-04T05:51:03.340283Z","steps":["trace[1530440544] 'agreement among raft nodes before linearized reading' (duration: 201.417546ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.377266Z","caller":"traceutil/trace.go:171","msg":"trace[72984170] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"157.813227ms","start":"2026-10-04T05:51:03.219434Z","end":"2026-10-04T05:51:03.377247Z","steps":["trace[72984170] 'process raft request' (duration: 157.31525ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:51:03.377421Z","caller":"traceutil/trace.go:171","msg":"trace[708326151] transaction","detail":"{read_only:false; response_revision:5207; number_of_response:1; }","duration":"157.701186ms","start":"2026-10-04T05:51:03.21971Z","end":"2026-10-04T05:51:03.377411Z","steps":["trace[708326151] 'process raft request' (duration: 157.137428ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T05:53:18.646204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3160} {"level":"info","ts":"2026-10-04T05:53:18.741639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3160,"took":"94.064885ms","hash":2409166129,"current-db-size-bytes":57888768,"current-db-size":"58 MB","current-db-size-in-use-bytes":31412224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T05:53:18.741704Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2409166129,"revision":3160,"compact-revision":-1}