{"level":"warn","ts":"2026-10-06T09:56:55.765146Z","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-06T09:56:55.765249Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.220: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.220:2380","--initial-cluster=instance=https://162.253.55.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.220: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-06T09:56:55.765348Z","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-06T09:56:55.765364Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-10-06T09:56:55.765391Z","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-06T09:56:55.766245Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"]} {"level":"info","ts":"2026-10-06T09:56:55.766393Z","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.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.220: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-06T09:56:55.771268Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.901119ms"} {"level":"info","ts":"2026-10-06T09:56:55.778774Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c62f064ba268721","cluster-id":"65b58f2bbdfe5647"} {"level":"info","ts":"2026-10-06T09:56:55.778935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=()"} {"level":"info","ts":"2026-10-06T09:56:55.778999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 0"} {"level":"info","ts":"2026-10-06T09:56:55.779018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c62f064ba268721 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-06T09:56:55.779026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 1"} {"level":"info","ts":"2026-10-06T09:56:55.7791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"warn","ts":"2026-10-06T09:56:55.78652Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-06T09:56:55.787867Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-06T09:56:55.789283Z","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-06T09:56:55.791683Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c62f064ba268721","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-06T09:56:55.79181Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c62f064ba268721","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-06T09:56:55.791817Z","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-06T09:56:55.791908Z","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-06T09:56:55.791928Z","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-06T09:56:55.793259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"info","ts":"2026-10-06T09:56:55.79403Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","added-peer-id":"4c62f064ba268721","added-peer-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-10-06T09:56:55.796381Z","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-06T09:56:55.796627Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c62f064ba268721","initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-06T09:56:55.796691Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-06T09:56:55.796744Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-10-06T09:56:55.796776Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-10-06T09:56:55.879868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 is starting a new election at term 1"} {"level":"info","ts":"2026-10-06T09:56:55.879949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-06T09:56:55.879994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgPreVoteResp from 4c62f064ba268721 at term 1"} {"level":"info","ts":"2026-10-06T09:56:55.880014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became candidate at term 2"} {"level":"info","ts":"2026-10-06T09:56:55.880021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgVoteResp from 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-10-06T09:56:55.880034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became leader at term 2"} {"level":"info","ts":"2026-10-06T09:56:55.880046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c62f064ba268721 elected leader 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-10-06T09:56:55.881044Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c62f064ba268721","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.220:2379]}","request-path":"/0/members/4c62f064ba268721/attributes","cluster-id":"65b58f2bbdfe5647","publish-timeout":"7s"} {"level":"info","ts":"2026-10-06T09:56:55.882018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T09:56:55.882396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:55.882018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-06T09:56:55.883323Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:55.883429Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:55.883497Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-06T09:56:55.883789Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-06T09:56:55.884111Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-06T09:56:55.884127Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-06T09:56:55.885304Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.220:2379"} {"level":"info","ts":"2026-10-06T09:57:38.246554Z","caller":"traceutil/trace.go:171","msg":"trace[1300914850] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"323.865248ms","start":"2026-10-06T09:57:37.9226Z","end":"2026-10-06T09:57:38.246465Z","steps":["trace[1300914850] 'read index received' (duration: 322.840278ms)","trace[1300914850] 'applied index is now lower than readState.Index' (duration: 1.02338ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T09:57:38.247383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.704244ms","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-10-06T09:57:38.24756Z","caller":"traceutil/trace.go:171","msg":"trace[972419420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"324.99513ms","start":"2026-10-06T09:57:37.922543Z","end":"2026-10-06T09:57:38.247538Z","steps":["trace[972419420] 'agreement among raft nodes before linearized reading' (duration: 324.567502ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:38.247634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:37.922504Z","time spent":"325.119052ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-06T09:57:38.247835Z","caller":"traceutil/trace.go:171","msg":"trace[40252746] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"642.992407ms","start":"2026-10-06T09:57:37.604824Z","end":"2026-10-06T09:57:38.247817Z","steps":["trace[40252746] 'process raft request' (duration: 640.889177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:38.248337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:37.604787Z","time spent":"643.098579ms","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T09:57:38.267159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.775755ms","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-10-06T09:57:38.267433Z","caller":"traceutil/trace.go:171","msg":"trace[328549914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"266.115652ms","start":"2026-10-06T09:57:38.001285Z","end":"2026-10-06T09:57:38.267401Z","steps":["trace[328549914] 'agreement among raft nodes before linearized reading' (duration: 246.499464ms)","trace[328549914] 'range keys from in-memory index tree' (duration: 19.20003ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T09:57:38.267673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.205718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jpt8h\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-10-06T09:57:38.267905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.047207ms","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-06T09:57:38.268015Z","caller":"traceutil/trace.go:171","msg":"trace[179506972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"341.18432ms","start":"2026-10-06T09:57:37.926807Z","end":"2026-10-06T09:57:38.267992Z","steps":["trace[179506972] 'agreement among raft nodes before linearized reading' (duration: 320.939701ms)","trace[179506972] 'range keys from in-memory index tree' (duration: 20.083436ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T09:57:38.268251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:37.92679Z","time spent":"341.446945ms","remote":"127.0.0.1:38612","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-06T09:57:38.267764Z","caller":"traceutil/trace.go:171","msg":"trace[1286495747] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jpt8h; range_end:; response_count:1; response_revision:588; }","duration":"287.331011ms","start":"2026-10-06T09:57:37.980413Z","end":"2026-10-06T09:57:38.267744Z","steps":["trace[1286495747] 'agreement among raft nodes before linearized reading' (duration: 267.380277ms)","trace[1286495747] 'range keys from in-memory index tree' (duration: 19.76537ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T09:57:38.69854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.924528ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T09:57:38.698688Z","caller":"traceutil/trace.go:171","msg":"trace[982711338] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:591; }","duration":"198.076241ms","start":"2026-10-06T09:57:38.500588Z","end":"2026-10-06T09:57:38.698665Z","steps":["trace[982711338] 'range keys from in-memory index tree' (duration: 197.909838ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:57:38.829694Z","caller":"traceutil/trace.go:171","msg":"trace[138056482] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"523.463104ms","start":"2026-10-06T09:57:38.306219Z","end":"2026-10-06T09:57:38.829682Z","steps":["trace[138056482] 'read index received' (duration: 30.543888ms)","trace[138056482] 'applied index is now lower than readState.Index' (duration: 492.918596ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:57:38.829754Z","caller":"traceutil/trace.go:171","msg":"trace[505344632] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"526.973201ms","start":"2026-10-06T09:57:38.302773Z","end":"2026-10-06T09:57:38.829746Z","steps":["trace[505344632] 'process raft request' (duration: 526.830449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:38.829816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:38.302753Z","time spent":"527.014353ms","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T09:57:38.829895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.676808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T09:57:38.829911Z","caller":"traceutil/trace.go:171","msg":"trace[62946485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"523.719179ms","start":"2026-10-06T09:57:38.306188Z","end":"2026-10-06T09:57:38.829907Z","steps":["trace[62946485] 'agreement among raft nodes before linearized reading' (duration: 523.692289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:38.829922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:38.306176Z","time spent":"523.74342ms","remote":"127.0.0.1:38374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-06T09:57:38.904651Z","caller":"traceutil/trace.go:171","msg":"trace[1621020267] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"448.250287ms","start":"2026-10-06T09:57:38.456363Z","end":"2026-10-06T09:57:38.904614Z","steps":["trace[1621020267] 'process raft request' (duration: 448.118974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:38.904694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.861771ms","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-06T09:57:38.904701Z","caller":"traceutil/trace.go:171","msg":"trace[28347260] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"563.638057ms","start":"2026-10-06T09:57:38.341044Z","end":"2026-10-06T09:57:38.904682Z","steps":["trace[28347260] 'process raft request' (duration: 558.905045ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:57:38.90476Z","caller":"traceutil/trace.go:171","msg":"trace[1599861042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"432.961793ms","start":"2026-10-06T09:57:38.471781Z","end":"2026-10-06T09:57:38.904743Z","steps":["trace[1599861042] 'agreement among raft nodes before linearized reading' (duration: 432.765459ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:57:38.904799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:38.47177Z","time spent":"433.020204ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-06T09:57:38.904803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:38.456338Z","time spent":"448.38398ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T09:57:38.904806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:57:38.341028Z","time spent":"563.738799ms","remote":"127.0.0.1:38682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-06T09:57:40.269618Z","caller":"traceutil/trace.go:171","msg":"trace[738539892] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"133.272271ms","start":"2026-10-06T09:57:40.136309Z","end":"2026-10-06T09:57:40.269581Z","steps":["trace[738539892] 'process raft request' (duration: 129.362406ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:58:22.803467Z","caller":"traceutil/trace.go:171","msg":"trace[603391754] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"306.056664ms","start":"2026-10-06T09:58:22.49738Z","end":"2026-10-06T09:58:22.803437Z","steps":["trace[603391754] 'process raft request' (duration: 305.895812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:58:22.80364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:58:22.497334Z","time spent":"306.222646ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T09:58:22.804936Z","caller":"traceutil/trace.go:171","msg":"trace[56312210] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1188; }","duration":"217.198293ms","start":"2026-10-06T09:58:22.587717Z","end":"2026-10-06T09:58:22.804916Z","steps":["trace[56312210] 'read index received' (duration: 215.638815ms)","trace[56312210] 'applied index is now lower than readState.Index' (duration: 1.558658ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:58:22.805162Z","caller":"traceutil/trace.go:171","msg":"trace[1327961755] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"234.890291ms","start":"2026-10-06T09:58:22.570123Z","end":"2026-10-06T09:58:22.805013Z","steps":["trace[1327961755] 'process raft request' (duration: 234.671538ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:58:22.805488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.619442ms","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-06T09:58:22.805645Z","caller":"traceutil/trace.go:171","msg":"trace[1164664380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1154; }","duration":"216.856236ms","start":"2026-10-06T09:58:22.588718Z","end":"2026-10-06T09:58:22.805574Z","steps":["trace[1164664380] 'agreement among raft nodes before linearized reading' (duration: 216.401518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:58:22.80567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.935135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-10-06T09:58:22.805733Z","caller":"traceutil/trace.go:171","msg":"trace[1208483807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1154; }","duration":"218.064777ms","start":"2026-10-06T09:58:22.587654Z","end":"2026-10-06T09:58:22.805719Z","steps":["trace[1208483807] 'agreement among raft nodes before linearized reading' (duration: 217.392056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:58:22.80591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.90473ms","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-10-06T09:58:22.806058Z","caller":"traceutil/trace.go:171","msg":"trace[1548188032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1154; }","duration":"216.122574ms","start":"2026-10-06T09:58:22.589914Z","end":"2026-10-06T09:58:22.806036Z","steps":["trace[1548188032] 'agreement among raft nodes before linearized reading' (duration: 215.31855ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:58:44.501586Z","caller":"traceutil/trace.go:171","msg":"trace[1592419144] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"132.294591ms","start":"2026-10-06T09:58:44.369261Z","end":"2026-10-06T09:58:44.501556Z","steps":["trace[1592419144] 'process raft request' (duration: 102.78126ms)","trace[1592419144] 'compare' (duration: 29.275117ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:59:14.958373Z","caller":"traceutil/trace.go:171","msg":"trace[966924961] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"171.122639ms","start":"2026-10-06T09:59:14.787194Z","end":"2026-10-06T09:59:14.958317Z","steps":["trace[966924961] 'read index received' (duration: 170.868996ms)","trace[966924961] 'applied index is now lower than readState.Index' (duration: 252.353µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:59:14.958427Z","caller":"traceutil/trace.go:171","msg":"trace[1649063466] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"442.103188ms","start":"2026-10-06T09:59:14.516302Z","end":"2026-10-06T09:59:14.958406Z","steps":["trace[1649063466] 'process raft request' (duration: 441.828404ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:14.958581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.371451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-10-06T09:59:14.958632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T09:59:14.516277Z","time spent":"442.209573ms","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T09:59:14.958647Z","caller":"traceutil/trace.go:171","msg":"trace[329073816] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1840; }","duration":"171.482797ms","start":"2026-10-06T09:59:14.787141Z","end":"2026-10-06T09:59:14.958624Z","steps":["trace[329073816] 'agreement among raft nodes before linearized reading' (duration: 171.319029ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:16.654347Z","caller":"traceutil/trace.go:171","msg":"trace[796300457] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"113.296714ms","start":"2026-10-06T09:59:16.541026Z","end":"2026-10-06T09:59:16.654323Z","steps":["trace[796300457] 'process raft request' (duration: 113.128356ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:27.697934Z","caller":"traceutil/trace.go:171","msg":"trace[2033154098] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"105.28875ms","start":"2026-10-06T09:59:27.592615Z","end":"2026-10-06T09:59:27.697904Z","steps":["trace[2033154098] 'process raft request' (duration: 105.134192ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:27.757628Z","caller":"traceutil/trace.go:171","msg":"trace[455696328] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"157.355883ms","start":"2026-10-06T09:59:27.600231Z","end":"2026-10-06T09:59:27.757587Z","steps":["trace[455696328] 'process raft request' (duration: 131.878033ms)","trace[455696328] 'compare' (duration: 25.173955ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:59:53.936659Z","caller":"traceutil/trace.go:171","msg":"trace[1081016778] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"147.793908ms","start":"2026-10-06T09:59:53.788836Z","end":"2026-10-06T09:59:53.93663Z","steps":["trace[1081016778] 'process raft request' (duration: 147.612201ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:54.089292Z","caller":"traceutil/trace.go:171","msg":"trace[785703734] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2183; }","duration":"152.662999ms","start":"2026-10-06T09:59:53.936607Z","end":"2026-10-06T09:59:54.08927Z","steps":["trace[785703734] 'read index received' (duration: 144.773396ms)","trace[785703734] 'applied index is now lower than readState.Index' (duration: 7.888663ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T09:59:54.089371Z","caller":"traceutil/trace.go:171","msg":"trace[863629892] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"220.221939ms","start":"2026-10-06T09:59:53.869125Z","end":"2026-10-06T09:59:54.089346Z","steps":["trace[863629892] 'process raft request' (duration: 212.304815ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:54.089425Z","caller":"traceutil/trace.go:171","msg":"trace[338171876] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"177.339023ms","start":"2026-10-06T09:59:53.912056Z","end":"2026-10-06T09:59:54.089395Z","steps":["trace[338171876] 'process raft request' (duration: 177.152856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T09:59:54.089523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.174744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-06T09:59:54.089582Z","caller":"traceutil/trace.go:171","msg":"trace[436461420] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2103; }","duration":"163.263818ms","start":"2026-10-06T09:59:53.926301Z","end":"2026-10-06T09:59:54.089565Z","steps":["trace[436461420] 'agreement among raft nodes before linearized reading' (duration: 163.061869ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T09:59:54.26079Z","caller":"traceutil/trace.go:171","msg":"trace[373315676] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"166.953519ms","start":"2026-10-06T09:59:54.093802Z","end":"2026-10-06T09:59:54.260756Z","steps":["trace[373315676] 'process raft request' (duration: 149.609278ms)","trace[373315676] 'compare' (duration: 17.120401ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:01:27.069603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.77988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:01:27.069703Z","caller":"traceutil/trace.go:171","msg":"trace[374250389] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2709; }","duration":"145.934005ms","start":"2026-10-06T10:01:26.923742Z","end":"2026-10-06T10:01:27.069676Z","steps":["trace[374250389] 'count revisions from in-memory index tree' (duration: 145.701797ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:46.474681Z","caller":"traceutil/trace.go:171","msg":"trace[1411397361] linearizableReadLoop","detail":"{readStateIndex:4876; appliedIndex:4875; }","duration":"127.515391ms","start":"2026-10-06T10:05:46.347123Z","end":"2026-10-06T10:05:46.474638Z","steps":["trace[1411397361] 'read index received' (duration: 127.137483ms)","trace[1411397361] 'applied index is now lower than readState.Index' (duration: 376.168µs)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:46.475142Z","caller":"traceutil/trace.go:171","msg":"trace[1727863974] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"129.147885ms","start":"2026-10-06T10:05:46.345976Z","end":"2026-10-06T10:05:46.475124Z","steps":["trace[1727863974] 'process raft request' (duration: 128.42339ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:46.476836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.668287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-10-06T10:05:46.476914Z","caller":"traceutil/trace.go:171","msg":"trace[1793286843] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4694; }","duration":"129.78952ms","start":"2026-10-06T10:05:46.347102Z","end":"2026-10-06T10:05:46.476891Z","steps":["trace[1793286843] 'agreement among raft nodes before linearized reading' (duration: 128.38225ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:54.335192Z","caller":"traceutil/trace.go:171","msg":"trace[561729507] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"167.000127ms","start":"2026-10-06T10:05:54.16817Z","end":"2026-10-06T10:05:54.33517Z","steps":["trace[561729507] 'process raft request' (duration: 166.854744ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:54.438286Z","caller":"traceutil/trace.go:171","msg":"trace[894064329] linearizableReadLoop","detail":"{readStateIndex:5070; appliedIndex:5068; }","duration":"108.742347ms","start":"2026-10-06T10:05:54.329518Z","end":"2026-10-06T10:05:54.43826Z","steps":["trace[894064329] 'read index received' (duration: 5.562757ms)","trace[894064329] 'applied index is now lower than readState.Index' (duration: 103.17826ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:54.438403Z","caller":"traceutil/trace.go:171","msg":"trace[2098637762] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"108.974562ms","start":"2026-10-06T10:05:54.329389Z","end":"2026-10-06T10:05:54.438364Z","steps":["trace[2098637762] 'process raft request' (duration: 88.999173ms)","trace[2098637762] 'compare' (duration: 19.749725ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:05:54.438467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.91477ms","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-10-06T10:05:54.438577Z","caller":"traceutil/trace.go:171","msg":"trace[994962648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:4881; }","duration":"109.095914ms","start":"2026-10-06T10:05:54.32947Z","end":"2026-10-06T10:05:54.438566Z","steps":["trace[994962648] 'agreement among raft nodes before linearized reading' (duration: 108.87774ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:05:57.414282Z","caller":"traceutil/trace.go:171","msg":"trace[418551535] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5106; }","duration":"111.665465ms","start":"2026-10-06T10:05:57.302594Z","end":"2026-10-06T10:05:57.41426Z","steps":["trace[418551535] 'read index received' (duration: 74.406426ms)","trace[418551535] 'applied index is now lower than readState.Index' (duration: 37.258129ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:05:57.414428Z","caller":"traceutil/trace.go:171","msg":"trace[797522249] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"113.8454ms","start":"2026-10-06T10:05:57.300532Z","end":"2026-10-06T10:05:57.414377Z","steps":["trace[797522249] 'process raft request' (duration: 113.616005ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.414502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.886179ms","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-10-06T10:05:57.414542Z","caller":"traceutil/trace.go:171","msg":"trace[2015191988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4918; }","duration":"111.967901ms","start":"2026-10-06T10:05:57.302564Z","end":"2026-10-06T10:05:57.414532Z","steps":["trace[2015191988] 'agreement among raft nodes before linearized reading' (duration: 111.817488ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.414677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.000362ms","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-06T10:05:57.414751Z","caller":"traceutil/trace.go:171","msg":"trace[2147001321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4918; }","duration":"112.106373ms","start":"2026-10-06T10:05:57.302627Z","end":"2026-10-06T10:05:57.414733Z","steps":["trace[2147001321] 'agreement among raft nodes before linearized reading' (duration: 111.902849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:05:57.414863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.99606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:05:57.414932Z","caller":"traceutil/trace.go:171","msg":"trace[1135755964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4918; }","duration":"110.096381ms","start":"2026-10-06T10:05:57.304823Z","end":"2026-10-06T10:05:57.41492Z","steps":["trace[1135755964] 'agreement among raft nodes before linearized reading' (duration: 109.983209ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:03.547876Z","caller":"traceutil/trace.go:171","msg":"trace[1832222899] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"100.899174ms","start":"2026-10-06T10:06:03.44695Z","end":"2026-10-06T10:06:03.547849Z","steps":["trace[1832222899] 'process raft request' (duration: 100.057467ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:03.569619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.183541ms","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-06T10:06:03.5697Z","caller":"traceutil/trace.go:171","msg":"trace[100490957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5013; }","duration":"114.286072ms","start":"2026-10-06T10:06:03.455399Z","end":"2026-10-06T10:06:03.569685Z","steps":["trace[100490957] 'agreement among raft nodes before linearized reading' (duration: 114.107199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:03.570231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.451859ms","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-10-06T10:06:03.570301Z","caller":"traceutil/trace.go:171","msg":"trace[2135023177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5013; }","duration":"106.574012ms","start":"2026-10-06T10:06:03.463712Z","end":"2026-10-06T10:06:03.570286Z","steps":["trace[2135023177] 'agreement among raft nodes before linearized reading' (duration: 106.378338ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:07.869785Z","caller":"traceutil/trace.go:171","msg":"trace[2104041896] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"317.69034ms","start":"2026-10-06T10:06:07.552038Z","end":"2026-10-06T10:06:07.869728Z","steps":["trace[2104041896] 'process raft request' (duration: 317.487296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:07.869946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.552004Z","time spent":"317.855434ms","remote":"127.0.0.1:38436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-06T10:06:07.87045Z","caller":"traceutil/trace.go:171","msg":"trace[1155443471] linearizableReadLoop","detail":"{readStateIndex:5236; appliedIndex:5235; }","duration":"315.177508ms","start":"2026-10-06T10:06:07.555243Z","end":"2026-10-06T10:06:07.87042Z","steps":["trace[1155443471] 'read index received' (duration: 314.246859ms)","trace[1155443471] 'applied index is now lower than readState.Index' (duration: 928.709µs)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:06:07.870735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.471705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-10-06T10:06:07.870931Z","caller":"traceutil/trace.go:171","msg":"trace[1973688005] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5040; }","duration":"315.701269ms","start":"2026-10-06T10:06:07.555211Z","end":"2026-10-06T10:06:07.870912Z","steps":["trace[1973688005] 'agreement among raft nodes before linearized reading' (duration: 315.269419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:07.871037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.5552Z","time spent":"315.824053ms","remote":"127.0.0.1:38778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9659,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-10-06T10:06:08.046968Z","caller":"traceutil/trace.go:171","msg":"trace[310679142] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"456.851806ms","start":"2026-10-06T10:06:07.59009Z","end":"2026-10-06T10:06:08.046941Z","steps":["trace[310679142] 'process raft request' (duration: 415.376034ms)","trace[310679142] 'compare' (duration: 41.191166ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:06:08.047284Z","caller":"traceutil/trace.go:171","msg":"trace[623025056] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5236; }","duration":"176.774208ms","start":"2026-10-06T10:06:07.870486Z","end":"2026-10-06T10:06:08.04726Z","steps":["trace[623025056] 'read index received' (duration: 134.916398ms)","trace[623025056] 'applied index is now lower than readState.Index' (duration: 41.85544ms)"],"step_count":2} {"level":"warn","ts":"2026-10-06T10:06:08.047275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.590068Z","time spent":"456.962689ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:06:08.047512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.856401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-10-06T10:06:08.047639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.246263ms","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-06T10:06:08.047733Z","caller":"traceutil/trace.go:171","msg":"trace[1927962721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5042; }","duration":"455.359086ms","start":"2026-10-06T10:06:07.592359Z","end":"2026-10-06T10:06:08.047718Z","steps":["trace[1927962721] 'agreement among raft nodes before linearized reading' (duration: 455.150472ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.047875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.277758ms","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":"warn","ts":"2026-10-06T10:06:08.047845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.59235Z","time spent":"455.478388ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-10-06T10:06:08.047914Z","caller":"traceutil/trace.go:171","msg":"trace[1941223987] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5042; }","duration":"412.33534ms","start":"2026-10-06T10:06:07.635566Z","end":"2026-10-06T10:06:08.047901Z","steps":["trace[1941223987] 'agreement among raft nodes before linearized reading' (duration: 412.240638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.047938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.635556Z","time spent":"412.37587ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-10-06T10:06:08.048079Z","caller":"traceutil/trace.go:171","msg":"trace[2053844282] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"457.855168ms","start":"2026-10-06T10:06:07.590214Z","end":"2026-10-06T10:06:08.048069Z","steps":["trace[2053844282] 'process raft request' (duration: 456.679183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.048136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.590193Z","time spent":"457.911239ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-06T10:06:08.048193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.988449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18dbe8b7b538956a\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-10-06T10:06:08.048278Z","caller":"traceutil/trace.go:171","msg":"trace[1736392943] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18dbe8b7b538956a; range_end:; response_count:1; response_revision:5042; }","duration":"173.108402ms","start":"2026-10-06T10:06:07.875155Z","end":"2026-10-06T10:06:08.048263Z","steps":["trace[1736392943] 'agreement among raft nodes before linearized reading' (duration: 172.913988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.048228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.878651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-06T10:06:08.048439Z","caller":"traceutil/trace.go:171","msg":"trace[2080659866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5042; }","duration":"488.146737ms","start":"2026-10-06T10:06:07.560273Z","end":"2026-10-06T10:06:08.04842Z","steps":["trace[2080659866] 'agreement among raft nodes before linearized reading' (duration: 487.594416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.048487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.560258Z","time spent":"488.217228ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-06T10:06:08.048541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.855756ms","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-06T10:06:08.048586Z","caller":"traceutil/trace.go:171","msg":"trace[796283267] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5042; }","duration":"172.914938ms","start":"2026-10-06T10:06:07.875658Z","end":"2026-10-06T10:06:08.048573Z","steps":["trace[796283267] 'agreement among raft nodes before linearized reading' (duration: 172.801736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.048715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.06526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-10-06T10:06:08.048746Z","caller":"traceutil/trace.go:171","msg":"trace[367059772] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5042; }","duration":"457.131522ms","start":"2026-10-06T10:06:07.591605Z","end":"2026-10-06T10:06:08.048736Z","steps":["trace[367059772] 'agreement among raft nodes before linearized reading' (duration: 456.982869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.048939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.633015ms","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-10-06T10:06:08.047547Z","caller":"traceutil/trace.go:171","msg":"trace[526605736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5042; }","duration":"450.909572ms","start":"2026-10-06T10:06:07.596628Z","end":"2026-10-06T10:06:08.047537Z","steps":["trace[526605736] 'agreement among raft nodes before linearized reading' (duration: 450.78285ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:08.048979Z","caller":"traceutil/trace.go:171","msg":"trace[1769520512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5042; }","duration":"449.696856ms","start":"2026-10-06T10:06:07.59927Z","end":"2026-10-06T10:06:08.048967Z","steps":["trace[1769520512] 'agreement among raft nodes before linearized reading' (duration: 449.588134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.048995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.591594Z","time spent":"457.250554ms","remote":"127.0.0.1:38540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-06T10:06:08.048998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.596618Z","time spent":"452.364752ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-06T10:06:08.049011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.599259Z","time spent":"449.744167ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-06T10:06:08.05087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.012591ms","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-06T10:06:08.050932Z","caller":"traceutil/trace.go:171","msg":"trace[915936433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5042; }","duration":"447.190175ms","start":"2026-10-06T10:06:07.603726Z","end":"2026-10-06T10:06:08.050916Z","steps":["trace[915936433] 'agreement among raft nodes before linearized reading' (duration: 444.273225ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.050964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-06T10:06:07.603713Z","time spent":"447.242576ms","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-06T10:06:08.224453Z","caller":"traceutil/trace.go:171","msg":"trace[2138296417] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"164.214296ms","start":"2026-10-06T10:06:08.060215Z","end":"2026-10-06T10:06:08.22443Z","steps":["trace[2138296417] 'process raft request' (duration: 114.928681ms)","trace[2138296417] 'compare' (duration: 49.140412ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:06:08.224718Z","caller":"traceutil/trace.go:171","msg":"trace[685065379] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"163.629043ms","start":"2026-10-06T10:06:08.061064Z","end":"2026-10-06T10:06:08.224693Z","steps":["trace[685065379] 'process raft request' (duration: 163.328887ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:08.225167Z","caller":"traceutil/trace.go:171","msg":"trace[445425807] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"163.720795ms","start":"2026-10-06T10:06:08.061423Z","end":"2026-10-06T10:06:08.225143Z","steps":["trace[445425807] 'process raft request' (duration: 163.139963ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:08.225291Z","caller":"traceutil/trace.go:171","msg":"trace[1074551455] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"158.840424ms","start":"2026-10-06T10:06:08.066436Z","end":"2026-10-06T10:06:08.225276Z","steps":["trace[1074551455] 'process raft request' (duration: 158.769733ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:08.225396Z","caller":"traceutil/trace.go:171","msg":"trace[1730271834] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"162.082811ms","start":"2026-10-06T10:06:08.063304Z","end":"2026-10-06T10:06:08.225387Z","steps":["trace[1730271834] 'process raft request' (duration: 161.788305ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:08.297566Z","caller":"traceutil/trace.go:171","msg":"trace[1554121815] linearizableReadLoop","detail":"{readStateIndex:5245; appliedIndex:5239; }","duration":"223.081991ms","start":"2026-10-06T10:06:08.074461Z","end":"2026-10-06T10:06:08.297543Z","steps":["trace[1554121815] 'read index received' (duration: 100.659714ms)","trace[1554121815] 'applied index is now lower than readState.Index' (duration: 122.421407ms)"],"step_count":2} {"level":"info","ts":"2026-10-06T10:06:08.297626Z","caller":"traceutil/trace.go:171","msg":"trace[1670243655] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"226.845428ms","start":"2026-10-06T10:06:08.070745Z","end":"2026-10-06T10:06:08.29759Z","steps":["trace[1670243655] 'process raft request' (duration: 226.674804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.297836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.289026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-06T10:06:08.297913Z","caller":"traceutil/trace.go:171","msg":"trace[1464373232] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5049; }","duration":"187.374577ms","start":"2026-10-06T10:06:08.110491Z","end":"2026-10-06T10:06:08.297865Z","steps":["trace[1464373232] 'agreement among raft nodes before linearized reading' (duration: 187.295216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-06T10:06:08.297926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.411267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-10-06T10:06:08.297966Z","caller":"traceutil/trace.go:171","msg":"trace[604503728] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5049; }","duration":"223.54967ms","start":"2026-10-06T10:06:08.074405Z","end":"2026-10-06T10:06:08.297955Z","steps":["trace[604503728] 'agreement among raft nodes before linearized reading' (duration: 223.293565ms)"],"step_count":1} {"level":"info","ts":"2026-10-06T10:06:56.08251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2862} {"level":"info","ts":"2026-10-06T10:06:56.256181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2862,"took":"172.348021ms","hash":2473933518,"current-db-size-bytes":52768768,"current-db-size":"53 MB","current-db-size-in-use-bytes":28618752,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-06T10:06:56.256269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2473933518,"revision":2862,"compact-revision":-1} {"level":"info","ts":"2026-10-06T10:11:56.094071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5469} {"level":"info","ts":"2026-10-06T10:11:56.184686Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5469,"took":"86.887059ms","hash":3980950266,"current-db-size-bytes":52768768,"current-db-size":"53 MB","current-db-size-in-use-bytes":18292736,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-06T10:11:56.184752Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3980950266,"revision":5469,"compact-revision":2862}