{"level":"warn","ts":"2026-07-16T06:44:07.3974Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:44:07.397505Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.136: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://199.19.213.136:2380","--initial-cluster=instance=https://199.19.213.136:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.136:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.136: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-07-16T06:44:07.397589Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:44:07.397605Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.136:2380"]} {"level":"info","ts":"2026-07-16T06:44:07.397625Z","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-07-16T06:44:07.399184Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.136:2379"]} {"level":"info","ts":"2026-07-16T06:44:07.399333Z","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://199.19.213.136:2380"],"listen-peer-urls":["https://199.19.213.136:2380"],"advertise-client-urls":["https://199.19.213.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.136: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-07-16T06:44:07.403333Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.763336ms"} {"level":"info","ts":"2026-07-16T06:44:07.408994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bcbe0b88c51dc5e5","cluster-id":"e591ddd6f6a32da9"} {"level":"info","ts":"2026-07-16T06:44:07.409044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T06:44:07.409077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became follower at term 0"} {"level":"info","ts":"2026-07-16T06:44:07.409084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bcbe0b88c51dc5e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T06:44:07.409092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became follower at term 1"} {"level":"info","ts":"2026-07-16T06:44:07.409125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 switched to configuration voters=(13600320606755997157)"} {"level":"warn","ts":"2026-07-16T06:44:07.414949Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T06:44:07.417035Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T06:44:07.418427Z","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-07-16T06:44:07.420358Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bcbe0b88c51dc5e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T06:44:07.42222Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bcbe0b88c51dc5e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T06:44:07.422743Z","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-07-16T06:44:07.422878Z","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-07-16T06:44:07.422909Z","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-07-16T06:44:07.423416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 switched to configuration voters=(13600320606755997157)"} {"level":"info","ts":"2026-07-16T06:44:07.423558Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e591ddd6f6a32da9","local-member-id":"bcbe0b88c51dc5e5","added-peer-id":"bcbe0b88c51dc5e5","added-peer-peer-urls":["https://199.19.213.136:2380"]} {"level":"info","ts":"2026-07-16T06:44:07.424719Z","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-07-16T06:44:07.424897Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.136:2380"} {"level":"info","ts":"2026-07-16T06:44:07.42493Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.136:2380"} {"level":"info","ts":"2026-07-16T06:44:07.424956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bcbe0b88c51dc5e5","initial-advertise-peer-urls":["https://199.19.213.136:2380"],"listen-peer-urls":["https://199.19.213.136:2380"],"advertise-client-urls":["https://199.19.213.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T06:44:07.424992Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T06:44:07.714197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T06:44:07.714253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T06:44:07.714275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 received MsgPreVoteResp from bcbe0b88c51dc5e5 at term 1"} {"level":"info","ts":"2026-07-16T06:44:07.714294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became candidate at term 2"} {"level":"info","ts":"2026-07-16T06:44:07.714306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 received MsgVoteResp from bcbe0b88c51dc5e5 at term 2"} {"level":"info","ts":"2026-07-16T06:44:07.714322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bcbe0b88c51dc5e5 became leader at term 2"} {"level":"info","ts":"2026-07-16T06:44:07.714339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bcbe0b88c51dc5e5 elected leader bcbe0b88c51dc5e5 at term 2"} {"level":"info","ts":"2026-07-16T06:44:07.715102Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:44:07.71598Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:44:07.71602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:44:07.715977Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bcbe0b88c51dc5e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.136:2379]}","request-path":"/0/members/bcbe0b88c51dc5e5/attributes","cluster-id":"e591ddd6f6a32da9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T06:44:07.716234Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T06:44:07.716282Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T06:44:07.716505Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e591ddd6f6a32da9","local-member-id":"bcbe0b88c51dc5e5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:44:07.716593Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:44:07.716622Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:44:07.719336Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.136:2379"} {"level":"info","ts":"2026-07-16T06:44:07.71979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T06:44:12.453385Z","caller":"traceutil/trace.go:171","msg":"trace[1014571842] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"122.137858ms","start":"2026-07-16T06:44:12.331233Z","end":"2026-07-16T06:44:12.453371Z","steps":["trace[1014571842] 'read index received' (duration: 122.127407ms)","trace[1014571842] 'applied index is now lower than readState.Index' (duration: 9.901µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:44:12.453468Z","caller":"traceutil/trace.go:171","msg":"trace[129565321] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"195.566005ms","start":"2026-07-16T06:44:12.257888Z","end":"2026-07-16T06:44:12.453454Z","steps":["trace[129565321] 'process raft request' (duration: 195.39452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.453505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.257361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-16T06:44:12.45354Z","caller":"traceutil/trace.go:171","msg":"trace[1460400805] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:267; }","duration":"122.341704ms","start":"2026-07-16T06:44:12.331191Z","end":"2026-07-16T06:44:12.453533Z","steps":["trace[1460400805] 'agreement among raft nodes before linearized reading' (duration: 122.261631ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:12.711349Z","caller":"traceutil/trace.go:171","msg":"trace[1726841164] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"379.097936ms","start":"2026-07-16T06:44:12.332217Z","end":"2026-07-16T06:44:12.711314Z","steps":["trace[1726841164] 'process raft request' (duration: 378.880819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.711878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:44:12.332203Z","time spent":"379.229419ms","remote":"127.0.0.1:33356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:44:12.712005Z","caller":"traceutil/trace.go:171","msg":"trace[1371147321] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"281.432707ms","start":"2026-07-16T06:44:12.430537Z","end":"2026-07-16T06:44:12.711969Z","steps":["trace[1371147321] 'process raft request' (duration: 280.716515ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:12.712212Z","caller":"traceutil/trace.go:171","msg":"trace[356464734] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"265.268546ms","start":"2026-07-16T06:44:12.446932Z","end":"2026-07-16T06:44:12.712201Z","steps":["trace[356464734] 'process raft request' (duration: 265.126672ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:12.71235Z","caller":"traceutil/trace.go:171","msg":"trace[905182872] transaction","detail":"{read_only:false; number_of_response:0; response_revision:270; }","duration":"264.804422ms","start":"2026-07-16T06:44:12.447536Z","end":"2026-07-16T06:44:12.71234Z","steps":["trace[905182872] 'process raft request' (duration: 264.559244ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:12.712383Z","caller":"traceutil/trace.go:171","msg":"trace[1814906773] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"267.057072ms","start":"2026-07-16T06:44:12.445317Z","end":"2026-07-16T06:44:12.712374Z","steps":["trace[1814906773] 'process raft request' (duration: 266.559786ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:12.712466Z","caller":"traceutil/trace.go:171","msg":"trace[1568566806] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"266.521396ms","start":"2026-07-16T06:44:12.445937Z","end":"2026-07-16T06:44:12.712459Z","steps":["trace[1568566806] 'process raft request' (duration: 266.063491ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:12.712444Z","caller":"traceutil/trace.go:171","msg":"trace[949571832] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"264.602086ms","start":"2026-07-16T06:44:12.447788Z","end":"2026-07-16T06:44:12.71239Z","steps":["trace[949571832] 'process raft request' (duration: 264.327266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.713112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.705917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:44:12.713288Z","caller":"traceutil/trace.go:171","msg":"trace[1230098232] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:271; }","duration":"381.900983ms","start":"2026-07-16T06:44:12.331371Z","end":"2026-07-16T06:44:12.713272Z","steps":["trace[1230098232] 'agreement among raft nodes before linearized reading' (duration: 381.647565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.713418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:44:12.331365Z","time spent":"382.013046ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-07-16T06:44:12.712174Z","caller":"traceutil/trace.go:171","msg":"trace[1717521891] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:274; }","duration":"258.712852ms","start":"2026-07-16T06:44:12.453439Z","end":"2026-07-16T06:44:12.712152Z","steps":["trace[1717521891] 'read index received' (duration: 257.563797ms)","trace[1717521891] 'applied index is now lower than readState.Index' (duration: 1.147865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:44:12.863476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.932993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:44:12.863602Z","caller":"traceutil/trace.go:171","msg":"trace[475701098] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"407.191707ms","start":"2026-07-16T06:44:12.456369Z","end":"2026-07-16T06:44:12.863561Z","steps":["trace[475701098] 'process raft request' (duration: 302.967155ms)","trace[475701098] 'compare' (duration: 103.770378ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:44:12.863631Z","caller":"traceutil/trace.go:171","msg":"trace[1696008471] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"148.503496ms","start":"2026-07-16T06:44:12.715116Z","end":"2026-07-16T06:44:12.863619Z","steps":["trace[1696008471] 'process raft request' (duration: 148.452493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.86369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:44:12.456359Z","time spent":"407.297412ms","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:44:12.863779Z","caller":"traceutil/trace.go:171","msg":"trace[1829157744] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:281; }","duration":"149.848027ms","start":"2026-07-16T06:44:12.713921Z","end":"2026-07-16T06:44:12.863769Z","steps":["trace[1829157744] 'read index received' (duration: 45.422868ms)","trace[1829157744] 'applied index is now lower than readState.Index' (duration: 104.424259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:44:12.863923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.317194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:44:12.863958Z","caller":"traceutil/trace.go:171","msg":"trace[456274530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:273; }","duration":"193.406388ms","start":"2026-07-16T06:44:12.670543Z","end":"2026-07-16T06:44:12.86395Z","steps":["trace[456274530] 'agreement among raft nodes before linearized reading' (duration: 193.304925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.864003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.653512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-07-16T06:44:12.864047Z","caller":"traceutil/trace.go:171","msg":"trace[1818428545] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:273; }","duration":"407.714544ms","start":"2026-07-16T06:44:12.456319Z","end":"2026-07-16T06:44:12.864034Z","steps":["trace[1818428545] 'agreement among raft nodes before linearized reading' (duration: 407.659153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.864078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:44:12.456309Z","time spent":"407.763915ms","remote":"127.0.0.1:33684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-16T06:44:12.863959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.381894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-07-16T06:44:12.864146Z","caller":"traceutil/trace.go:171","msg":"trace[903696900] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:273; }","duration":"149.60281ms","start":"2026-07-16T06:44:12.714529Z","end":"2026-07-16T06:44:12.864132Z","steps":["trace[903696900] 'agreement among raft nodes before linearized reading' (duration: 149.381134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.864226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.824682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-07-16T06:44:12.864258Z","caller":"traceutil/trace.go:171","msg":"trace[1801102885] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:273; }","duration":"145.886534ms","start":"2026-07-16T06:44:12.718363Z","end":"2026-07-16T06:44:12.86425Z","steps":["trace[1801102885] 'agreement among raft nodes before linearized reading' (duration: 145.827592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.864389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.533148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-07-16T06:44:12.864428Z","caller":"traceutil/trace.go:171","msg":"trace[960229388] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:273; }","duration":"149.5928ms","start":"2026-07-16T06:44:12.714825Z","end":"2026-07-16T06:44:12.864418Z","steps":["trace[960229388] 'agreement among raft nodes before linearized reading' (duration: 149.538698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:12.864545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.92028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-07-16T06:44:12.864583Z","caller":"traceutil/trace.go:171","msg":"trace[349288399] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:273; }","duration":"149.987562ms","start":"2026-07-16T06:44:12.714587Z","end":"2026-07-16T06:44:12.864575Z","steps":["trace[349288399] 'agreement among raft nodes before linearized reading' (duration: 149.9173ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:44.123675Z","caller":"traceutil/trace.go:171","msg":"trace[1304333244] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"139.663046ms","start":"2026-07-16T06:44:43.983986Z","end":"2026-07-16T06:44:44.123649Z","steps":["trace[1304333244] 'process raft request' (duration: 139.510116ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:44.180297Z","caller":"traceutil/trace.go:171","msg":"trace[1888317542] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"195.781962ms","start":"2026-07-16T06:44:43.984009Z","end":"2026-07-16T06:44:44.179791Z","steps":["trace[1888317542] 'process raft request' (duration: 195.493424ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:44.185159Z","caller":"traceutil/trace.go:171","msg":"trace[332611945] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"197.971621ms","start":"2026-07-16T06:44:43.986684Z","end":"2026-07-16T06:44:44.184656Z","steps":["trace[332611945] 'process raft request' (duration: 193.034379ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:44.185376Z","caller":"traceutil/trace.go:171","msg":"trace[1208936547] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"192.496055ms","start":"2026-07-16T06:44:43.992157Z","end":"2026-07-16T06:44:44.184653Z","steps":["trace[1208936547] 'process raft request' (duration: 189.099025ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:44.186646Z","caller":"traceutil/trace.go:171","msg":"trace[2011845620] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"100.417052ms","start":"2026-07-16T06:44:44.086219Z","end":"2026-07-16T06:44:44.186636Z","steps":["trace[2011845620] 'process raft request' (duration: 100.220283ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:48.579649Z","caller":"traceutil/trace.go:171","msg":"trace[1819406454] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"118.696349ms","start":"2026-07-16T06:44:48.460931Z","end":"2026-07-16T06:44:48.579627Z","steps":["trace[1819406454] 'read index received' (duration: 117.934782ms)","trace[1819406454] 'applied index is now lower than readState.Index' (duration: 760.617µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:44:48.579691Z","caller":"traceutil/trace.go:171","msg":"trace[2003346910] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"143.167879ms","start":"2026-07-16T06:44:48.4365Z","end":"2026-07-16T06:44:48.579668Z","steps":["trace[2003346910] 'process raft request' (duration: 142.586682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:48.579809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.846999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:44:48.579896Z","caller":"traceutil/trace.go:171","msg":"trace[811938166] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:621; }","duration":"118.962828ms","start":"2026-07-16T06:44:48.460912Z","end":"2026-07-16T06:44:48.579875Z","steps":["trace[811938166] 'agreement among raft nodes before linearized reading' (duration: 118.818099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:48.579839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.846629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:44:48.580137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.841049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:44:48.580199Z","caller":"traceutil/trace.go:171","msg":"trace[1950287419] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:621; }","duration":"118.909649ms","start":"2026-07-16T06:44:48.461273Z","end":"2026-07-16T06:44:48.580182Z","steps":["trace[1950287419] 'agreement among raft nodes before linearized reading' (duration: 118.825879ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:48.580154Z","caller":"traceutil/trace.go:171","msg":"trace[1728469479] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:621; }","duration":"119.185207ms","start":"2026-07-16T06:44:48.460959Z","end":"2026-07-16T06:44:48.580144Z","steps":["trace[1728469479] 'agreement among raft nodes before linearized reading' (duration: 118.816499ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:50.229979Z","caller":"traceutil/trace.go:171","msg":"trace[638161418] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"231.490267ms","start":"2026-07-16T06:44:49.998449Z","end":"2026-07-16T06:44:50.229939Z","steps":["trace[638161418] 'process raft request' (duration: 228.170907ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:44:50.759178Z","caller":"traceutil/trace.go:171","msg":"trace[979533042] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"445.635345ms","start":"2026-07-16T06:44:50.313523Z","end":"2026-07-16T06:44:50.759159Z","steps":["trace[979533042] 'process raft request' (duration: 445.527425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:50.759293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:44:50.313507Z","time spent":"445.727854ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:44:50.759804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.529594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-67dp9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-16T06:44:50.759892Z","caller":"traceutil/trace.go:171","msg":"trace[925392058] range","detail":"{range_begin:/registry/pods/kube-system/cilium-67dp9; range_end:; response_count:1; response_revision:636; }","duration":"321.667173ms","start":"2026-07-16T06:44:50.438215Z","end":"2026-07-16T06:44:50.759882Z","steps":["trace[925392058] 'agreement among raft nodes before linearized reading' (duration: 321.399764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:44:50.759958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:44:50.438197Z","time spent":"321.752893ms","remote":"127.0.0.1:33462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-67dp9\" "} {"level":"info","ts":"2026-07-16T06:44:50.759583Z","caller":"traceutil/trace.go:171","msg":"trace[1890357369] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:656; }","duration":"321.244265ms","start":"2026-07-16T06:44:50.438254Z","end":"2026-07-16T06:44:50.759498Z","steps":["trace[1890357369] 'read index received' (duration: 321.236175ms)","trace[1890357369] 'applied index is now lower than readState.Index' (duration: 6.45µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:45:47.943619Z","caller":"traceutil/trace.go:171","msg":"trace[1050497436] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"140.943533ms","start":"2026-07-16T06:45:47.802654Z","end":"2026-07-16T06:45:47.943597Z","steps":["trace[1050497436] 'process raft request' (duration: 119.828908ms)","trace[1050497436] 'compare' (duration: 20.856091ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:01.470197Z","caller":"traceutil/trace.go:171","msg":"trace[1519642908] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"140.344921ms","start":"2026-07-16T06:46:01.329834Z","end":"2026-07-16T06:46:01.470179Z","steps":["trace[1519642908] 'process raft request' (duration: 71.028441ms)","trace[1519642908] 'compare' (duration: 68.995884ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:13.173173Z","caller":"traceutil/trace.go:171","msg":"trace[71712268] transaction","detail":"{read_only:false; response_revision:1536; number_of_response:1; }","duration":"108.61245ms","start":"2026-07-16T06:46:13.06454Z","end":"2026-07-16T06:46:13.173153Z","steps":["trace[71712268] 'process raft request' (duration: 42.84685ms)","trace[71712268] 'compare' (duration: 65.554995ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:13.173172Z","caller":"traceutil/trace.go:171","msg":"trace[377386337] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1536; }","duration":"107.105602ms","start":"2026-07-16T06:46:13.066044Z","end":"2026-07-16T06:46:13.17315Z","steps":["trace[377386337] 'process raft request' (duration: 107.05327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.91143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.255212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fdda08e4-cc89-4bea-9a29-a0da3b99cbdf\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-16T06:46:34.911503Z","caller":"traceutil/trace.go:171","msg":"trace[1623148429] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fdda08e4-cc89-4bea-9a29-a0da3b99cbdf; range_end:; response_count:1; response_revision:1912; }","duration":"117.414657ms","start":"2026-07-16T06:46:34.794073Z","end":"2026-07-16T06:46:34.911487Z","steps":["trace[1623148429] 'range keys from in-memory index tree' (duration: 117.035688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.911495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.540282ms","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":"info","ts":"2026-07-16T06:46:34.91154Z","caller":"traceutil/trace.go:171","msg":"trace[700073527] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1912; }","duration":"334.626754ms","start":"2026-07-16T06:46:34.576901Z","end":"2026-07-16T06:46:34.911528Z","steps":["trace[700073527] 'range keys from in-memory index tree' (duration: 334.385468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.911563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:34.57688Z","time spent":"334.677054ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-16T06:46:34.911615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.98295ms","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-07-16T06:46:34.911662Z","caller":"traceutil/trace.go:171","msg":"trace[1089254533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1912; }","duration":"329.081242ms","start":"2026-07-16T06:46:34.582567Z","end":"2026-07-16T06:46:34.911649Z","steps":["trace[1089254533] 'range keys from in-memory index tree' (duration: 328.843107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:34.911703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:46:34.582546Z","time spent":"329.149053ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T06:46:35.123445Z","caller":"traceutil/trace.go:171","msg":"trace[854036840] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"179.94301ms","start":"2026-07-16T06:46:34.943468Z","end":"2026-07-16T06:46:35.123411Z","steps":["trace[854036840] 'process raft request' (duration: 158.59205ms)","trace[854036840] 'compare' (duration: 21.011353ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:35.395346Z","caller":"traceutil/trace.go:171","msg":"trace[1436146125] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"186.446899ms","start":"2026-07-16T06:46:35.20887Z","end":"2026-07-16T06:46:35.395317Z","steps":["trace[1436146125] 'process raft request' (duration: 186.375417ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:35.395557Z","caller":"traceutil/trace.go:171","msg":"trace[63355157] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"187.213787ms","start":"2026-07-16T06:46:35.208299Z","end":"2026-07-16T06:46:35.395513Z","steps":["trace[63355157] 'process raft request' (duration: 186.842909ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:35.395615Z","caller":"traceutil/trace.go:171","msg":"trace[165397352] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"192.191586ms","start":"2026-07-16T06:46:35.20341Z","end":"2026-07-16T06:46:35.395602Z","steps":["trace[165397352] 'process raft request' (duration: 191.44299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:47.363226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.382518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:46:47.363323Z","caller":"traceutil/trace.go:171","msg":"trace[284346903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2021; }","duration":"190.542922ms","start":"2026-07-16T06:46:47.172756Z","end":"2026-07-16T06:46:47.363299Z","steps":["trace[284346903] 'range keys from in-memory index tree' (duration: 190.292586ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:46:49.921862Z","caller":"traceutil/trace.go:171","msg":"trace[1192765146] linearizableReadLoop","detail":"{readStateIndex:2110; appliedIndex:2109; }","duration":"118.553921ms","start":"2026-07-16T06:46:49.803264Z","end":"2026-07-16T06:46:49.921818Z","steps":["trace[1192765146] 'read index received' (duration: 118.373907ms)","trace[1192765146] 'applied index is now lower than readState.Index' (duration: 179.414µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:46:49.921877Z","caller":"traceutil/trace.go:171","msg":"trace[900619862] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"119.974373ms","start":"2026-07-16T06:46:49.801885Z","end":"2026-07-16T06:46:49.92186Z","steps":["trace[900619862] 'process raft request' (duration: 119.801789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:46:49.922032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.755556ms","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-07-16T06:46:49.922061Z","caller":"traceutil/trace.go:171","msg":"trace[1428725525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2035; }","duration":"118.821087ms","start":"2026-07-16T06:46:49.803232Z","end":"2026-07-16T06:46:49.922053Z","steps":["trace[1428725525] 'agreement among raft nodes before linearized reading' (duration: 118.697684ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:24.248511Z","caller":"traceutil/trace.go:171","msg":"trace[336757611] linearizableReadLoop","detail":"{readStateIndex:2315; appliedIndex:2314; }","duration":"123.286257ms","start":"2026-07-16T06:47:24.125194Z","end":"2026-07-16T06:47:24.248481Z","steps":["trace[336757611] 'read index received' (duration: 123.118092ms)","trace[336757611] 'applied index is now lower than readState.Index' (duration: 166.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:47:24.248565Z","caller":"traceutil/trace.go:171","msg":"trace[1193630515] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"128.567559ms","start":"2026-07-16T06:47:24.119968Z","end":"2026-07-16T06:47:24.248535Z","steps":["trace[1193630515] 'process raft request' (duration: 128.342483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:24.248812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.593634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-16T06:47:24.248871Z","caller":"traceutil/trace.go:171","msg":"trace[1872791644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2232; }","duration":"123.665696ms","start":"2026-07-16T06:47:24.12519Z","end":"2026-07-16T06:47:24.248856Z","steps":["trace[1872791644] 'agreement among raft nodes before linearized reading' (duration: 123.453941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:24.555861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.055683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:47:24.556094Z","caller":"traceutil/trace.go:171","msg":"trace[2103827512] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"214.73409ms","start":"2026-07-16T06:47:24.341304Z","end":"2026-07-16T06:47:24.556038Z","steps":["trace[2103827512] 'process raft request' (duration: 214.696269ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:24.556252Z","caller":"traceutil/trace.go:171","msg":"trace[268958143] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"335.39146ms","start":"2026-07-16T06:47:24.220849Z","end":"2026-07-16T06:47:24.55624Z","steps":["trace[268958143] 'process raft request' (duration: 143.772913ms)","trace[268958143] 'compare' (duration: 190.865048ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:47:24.55634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:24.220827Z","time spent":"335.453111ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:47:24.556341Z","caller":"traceutil/trace.go:171","msg":"trace[621586706] linearizableReadLoop","detail":"{readStateIndex:2316; appliedIndex:2315; }","duration":"307.713562ms","start":"2026-07-16T06:47:24.248604Z","end":"2026-07-16T06:47:24.556318Z","steps":["trace[621586706] 'read index received' (duration: 116.030933ms)","trace[621586706] 'applied index is now lower than readState.Index' (duration: 191.680869ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:47:24.55645Z","caller":"traceutil/trace.go:171","msg":"trace[609201006] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"303.039094ms","start":"2026-07-16T06:47:24.253375Z","end":"2026-07-16T06:47:24.556414Z","steps":["trace[609201006] 'process raft request' (duration: 302.570373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:24.556579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.62544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T06:47:24.556627Z","caller":"traceutil/trace.go:171","msg":"trace[258491084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2235; }","duration":"430.718481ms","start":"2026-07-16T06:47:24.125896Z","end":"2026-07-16T06:47:24.556615Z","steps":["trace[258491084] 'agreement among raft nodes before linearized reading' (duration: 430.562917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:24.556638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.213777ms","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-07-16T06:47:24.556669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:24.125886Z","time spent":"430.770682ms","remote":"127.0.0.1:33550","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-07-16T06:47:24.556638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.292411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:47:24.55665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:24.253349Z","time spent":"303.177848ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:47:24.556717Z","caller":"traceutil/trace.go:171","msg":"trace[1582204687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2235; }","duration":"382.393673ms","start":"2026-07-16T06:47:24.174311Z","end":"2026-07-16T06:47:24.556705Z","steps":["trace[1582204687] 'agreement among raft nodes before linearized reading' (duration: 382.299051ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:24.556728Z","caller":"traceutil/trace.go:171","msg":"trace[264081264] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2235; }","duration":"297.32537ms","start":"2026-07-16T06:47:24.259371Z","end":"2026-07-16T06:47:24.556696Z","steps":["trace[264081264] 'agreement among raft nodes before linearized reading' (duration: 297.124895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:24.556674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.463249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T06:47:24.556833Z","caller":"traceutil/trace.go:171","msg":"trace[419966044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2235; }","duration":"427.707825ms","start":"2026-07-16T06:47:24.129111Z","end":"2026-07-16T06:47:24.556818Z","steps":["trace[419966044] 'agreement among raft nodes before linearized reading' (duration: 427.231103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:24.5569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:24.129104Z","time spent":"427.784717ms","remote":"127.0.0.1:33550","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":"warn","ts":"2026-07-16T06:47:24.556752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:24.174292Z","time spent":"382.452314ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:47:24.557094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.106131ms","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-07-16T06:47:24.557161Z","caller":"traceutil/trace.go:171","msg":"trace[866471433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2235; }","duration":"431.219313ms","start":"2026-07-16T06:47:24.125925Z","end":"2026-07-16T06:47:24.557145Z","steps":["trace[866471433] 'agreement among raft nodes before linearized reading' (duration: 430.483665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:47:24.557229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:47:24.12591Z","time spent":"431.306096ms","remote":"127.0.0.1:33550","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":"info","ts":"2026-07-16T06:47:24.672608Z","caller":"traceutil/trace.go:171","msg":"trace[1175324997] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"111.041767ms","start":"2026-07-16T06:47:24.561484Z","end":"2026-07-16T06:47:24.672526Z","steps":["trace[1175324997] 'process raft request' (duration: 106.3732ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:47:24.672859Z","caller":"traceutil/trace.go:171","msg":"trace[496790251] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"111.244613ms","start":"2026-07-16T06:47:24.561545Z","end":"2026-07-16T06:47:24.67279Z","steps":["trace[496790251] 'process raft request' (duration: 110.938474ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:37.782983Z","caller":"traceutil/trace.go:171","msg":"trace[1661897113] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"122.922074ms","start":"2026-07-16T06:48:37.660032Z","end":"2026-07-16T06:48:37.782954Z","steps":["trace[1661897113] 'process raft request' (duration: 122.674417ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:27.938854Z","caller":"traceutil/trace.go:171","msg":"trace[1618611958] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"133.335174ms","start":"2026-07-16T06:52:27.80549Z","end":"2026-07-16T06:52:27.938825Z","steps":["trace[1618611958] 'process raft request' (duration: 133.193381ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:28.386611Z","caller":"traceutil/trace.go:171","msg":"trace[178529534] linearizableReadLoop","detail":"{readStateIndex:4820; appliedIndex:4819; }","duration":"152.286645ms","start":"2026-07-16T06:52:28.234301Z","end":"2026-07-16T06:52:28.386588Z","steps":["trace[178529534] 'read index received' (duration: 152.186283ms)","trace[178529534] 'applied index is now lower than readState.Index' (duration: 99.532µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:28.386749Z","caller":"traceutil/trace.go:171","msg":"trace[2099612825] transaction","detail":"{read_only:false; response_revision:4645; number_of_response:1; }","duration":"153.243437ms","start":"2026-07-16T06:52:28.233404Z","end":"2026-07-16T06:52:28.386648Z","steps":["trace[2099612825] 'process raft request' (duration: 152.970451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:28.386871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.555121ms","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-07-16T06:52:28.386914Z","caller":"traceutil/trace.go:171","msg":"trace[1510464435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4645; }","duration":"152.662993ms","start":"2026-07-16T06:52:28.234239Z","end":"2026-07-16T06:52:28.386902Z","steps":["trace[1510464435] 'agreement among raft nodes before linearized reading' (duration: 152.50221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:28.387065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.522731ms","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":"info","ts":"2026-07-16T06:52:28.387117Z","caller":"traceutil/trace.go:171","msg":"trace[1225554292] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:4645; }","duration":"104.618453ms","start":"2026-07-16T06:52:28.282482Z","end":"2026-07-16T06:52:28.3871Z","steps":["trace[1225554292] 'agreement among raft nodes before linearized reading' (duration: 104.535351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:28.387441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.357284ms","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-07-16T06:52:28.387505Z","caller":"traceutil/trace.go:171","msg":"trace[1237405248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4645; }","duration":"150.437025ms","start":"2026-07-16T06:52:28.237052Z","end":"2026-07-16T06:52:28.387489Z","steps":["trace[1237405248] 'agreement among raft nodes before linearized reading' (duration: 150.276672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:28.388332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.495221ms","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-07-16T06:52:28.388444Z","caller":"traceutil/trace.go:171","msg":"trace[831011555] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4645; }","duration":"152.675405ms","start":"2026-07-16T06:52:28.23574Z","end":"2026-07-16T06:52:28.388415Z","steps":["trace[831011555] 'agreement among raft nodes before linearized reading' (duration: 151.267104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:34.416991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.839075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:34.417112Z","caller":"traceutil/trace.go:171","msg":"trace[222331780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4755; }","duration":"244.999009ms","start":"2026-07-16T06:52:34.17209Z","end":"2026-07-16T06:52:34.417089Z","steps":["trace[222331780] 'range keys from in-memory index tree' (duration: 244.768844ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:34.417159Z","caller":"traceutil/trace.go:171","msg":"trace[354519726] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"227.06927ms","start":"2026-07-16T06:52:34.190054Z","end":"2026-07-16T06:52:34.417123Z","steps":["trace[354519726] 'process raft request' (duration: 212.647339ms)","trace[354519726] 'compare' (duration: 13.87688ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:34.41717Z","caller":"traceutil/trace.go:171","msg":"trace[484284270] linearizableReadLoop","detail":"{readStateIndex:4933; appliedIndex:4932; }","duration":"158.276803ms","start":"2026-07-16T06:52:34.258858Z","end":"2026-07-16T06:52:34.417135Z","steps":["trace[484284270] 'read index received' (duration: 143.81603ms)","trace[484284270] 'applied index is now lower than readState.Index' (duration: 14.458793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:34.417464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.578369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-514fea8f-c1a9-4f37-a4c0-f235b5a3a935\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-16T06:52:34.417574Z","caller":"traceutil/trace.go:171","msg":"trace[1019130818] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-514fea8f-c1a9-4f37-a4c0-f235b5a3a935; range_end:; response_count:1; response_revision:4756; }","duration":"158.696912ms","start":"2026-07-16T06:52:34.258813Z","end":"2026-07-16T06:52:34.41751Z","steps":["trace[1019130818] 'agreement among raft nodes before linearized reading' (duration: 158.454217ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:34.586497Z","caller":"traceutil/trace.go:171","msg":"trace[1925412598] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"136.448179ms","start":"2026-07-16T06:52:34.450029Z","end":"2026-07-16T06:52:34.586477Z","steps":["trace[1925412598] 'process raft request' (duration: 136.335647ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:34.619262Z","caller":"traceutil/trace.go:171","msg":"trace[326540746] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"168.33862ms","start":"2026-07-16T06:52:34.450902Z","end":"2026-07-16T06:52:34.61924Z","steps":["trace[326540746] 'process raft request' (duration: 168.190607ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:34.619457Z","caller":"traceutil/trace.go:171","msg":"trace[1589081594] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"167.207026ms","start":"2026-07-16T06:52:34.452239Z","end":"2026-07-16T06:52:34.619446Z","steps":["trace[1589081594] 'process raft request' (duration: 166.945041ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:35.082354Z","caller":"traceutil/trace.go:171","msg":"trace[2078798934] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4944; }","duration":"141.510622ms","start":"2026-07-16T06:52:34.940816Z","end":"2026-07-16T06:52:35.082327Z","steps":["trace[2078798934] 'read index received' (duration: 141.478301ms)","trace[2078798934] 'applied index is now lower than readState.Index' (duration: 31.331µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:35.082366Z","caller":"traceutil/trace.go:171","msg":"trace[944991959] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"162.155679ms","start":"2026-07-16T06:52:34.920172Z","end":"2026-07-16T06:52:35.082328Z","steps":["trace[944991959] 'process raft request' (duration: 162.017326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:35.082502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.666295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:35.082558Z","caller":"traceutil/trace.go:171","msg":"trace[1897288093] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:4768; }","duration":"141.754017ms","start":"2026-07-16T06:52:34.940783Z","end":"2026-07-16T06:52:35.082537Z","steps":["trace[1897288093] 'agreement among raft nodes before linearized reading' (duration: 141.616494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:35.088612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.099433ms","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":"info","ts":"2026-07-16T06:52:35.088648Z","caller":"traceutil/trace.go:171","msg":"trace[1810571108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4769; }","duration":"141.160425ms","start":"2026-07-16T06:52:34.94748Z","end":"2026-07-16T06:52:35.08864Z","steps":["trace[1810571108] 'agreement among raft nodes before linearized reading' (duration: 141.047702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:46.25653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.077643ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:52:46.256947Z","caller":"traceutil/trace.go:171","msg":"trace[1287377507] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"314.681268ms","start":"2026-07-16T06:52:45.942193Z","end":"2026-07-16T06:52:46.256874Z","steps":["trace[1287377507] 'process raft request' (duration: 104.175686ms)","trace[1287377507] 'compare' (duration: 209.726945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:46.257212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:45.94217Z","time spent":"314.905083ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:52:46.277653Z","caller":"traceutil/trace.go:171","msg":"trace[48313459] linearizableReadLoop","detail":"{readStateIndex:5106; appliedIndex:5104; }","duration":"233.975728ms","start":"2026-07-16T06:52:46.043658Z","end":"2026-07-16T06:52:46.277634Z","steps":["trace[48313459] 'read index received' (duration: 2.759351ms)","trace[48313459] 'applied index is now lower than readState.Index' (duration: 231.215107ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:46.277793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.228863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:46.277828Z","caller":"traceutil/trace.go:171","msg":"trace[1996094448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4923; }","duration":"106.270583ms","start":"2026-07-16T06:52:46.171548Z","end":"2026-07-16T06:52:46.277818Z","steps":["trace[1996094448] 'agreement among raft nodes before linearized reading' (duration: 106.204222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:46.277847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.174732ms","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-07-16T06:52:46.277926Z","caller":"traceutil/trace.go:171","msg":"trace[1276518479] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4923; }","duration":"234.308545ms","start":"2026-07-16T06:52:46.043597Z","end":"2026-07-16T06:52:46.277906Z","steps":["trace[1276518479] 'agreement among raft nodes before linearized reading' (duration: 234.07147ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:53:25.385035Z","caller":"traceutil/trace.go:171","msg":"trace[1310061858] transaction","detail":"{read_only:false; response_revision:5271; number_of_response:1; }","duration":"112.672516ms","start":"2026-07-16T06:53:25.27233Z","end":"2026-07-16T06:53:25.385002Z","steps":["trace[1310061858] 'process raft request' (duration: 38.592332ms)","trace[1310061858] 'compare' (duration: 73.620173ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:54:07.747865Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2899} {"level":"info","ts":"2026-07-16T06:54:07.820109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2899,"took":"70.969552ms","hash":1362237867,"current-db-size-bytes":53280768,"current-db-size":"53 MB","current-db-size-in-use-bytes":29335552,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-16T06:54:07.82016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1362237867,"revision":2899,"compact-revision":-1} {"level":"warn","ts":"2026-07-16T06:55:47.153332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.695597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:55:47.153424Z","caller":"traceutil/trace.go:171","msg":"trace[1486312458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6162; }","duration":"537.821579ms","start":"2026-07-16T06:55:46.615586Z","end":"2026-07-16T06:55:47.153407Z","steps":["trace[1486312458] 'range keys from in-memory index tree' (duration: 537.631405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:55:47.153454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:55:46.615571Z","time spent":"537.876241ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:55:47.153497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.008691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:55:47.153586Z","caller":"traceutil/trace.go:171","msg":"trace[2083180184] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:6162; }","duration":"102.143965ms","start":"2026-07-16T06:55:47.051423Z","end":"2026-07-16T06:55:47.153567Z","steps":["trace[2083180184] 'count revisions from in-memory index tree' (duration: 101.93914ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:55:47.897122Z","caller":"traceutil/trace.go:171","msg":"trace[1161231033] transaction","detail":"{read_only:false; response_revision:6166; number_of_response:1; }","duration":"101.260954ms","start":"2026-07-16T06:55:47.795831Z","end":"2026-07-16T06:55:47.897092Z","steps":["trace[1161231033] 'process raft request' (duration: 60.199867ms)","trace[1161231033] 'compare' (duration: 40.944924ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:55:49.139003Z","caller":"traceutil/trace.go:171","msg":"trace[1066452885] transaction","detail":"{read_only:false; response_revision:6175; number_of_response:1; }","duration":"108.080742ms","start":"2026-07-16T06:55:49.0309Z","end":"2026-07-16T06:55:49.13898Z","steps":["trace[1066452885] 'process raft request' (duration: 107.940478ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:56:50.566501Z","caller":"traceutil/trace.go:171","msg":"trace[1074780017] transaction","detail":"{read_only:false; response_revision:6701; number_of_response:1; }","duration":"149.982598ms","start":"2026-07-16T06:56:50.416497Z","end":"2026-07-16T06:56:50.56648Z","steps":["trace[1074780017] 'process raft request' (duration: 109.581283ms)","trace[1074780017] 'compare' (duration: 40.024295ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:56:50.566543Z","caller":"traceutil/trace.go:171","msg":"trace[841938173] transaction","detail":"{read_only:false; response_revision:6702; number_of_response:1; }","duration":"150.037589ms","start":"2026-07-16T06:56:50.416496Z","end":"2026-07-16T06:56:50.566534Z","steps":["trace[841938173] 'process raft request' (duration: 149.835534ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:59:07.753074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5599} {"level":"info","ts":"2026-07-16T06:59:07.8291Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5599,"took":"74.534825ms","hash":1205229432,"current-db-size-bytes":53280768,"current-db-size":"53 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-16T06:59:07.829157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1205229432,"revision":5599,"compact-revision":2899}