{"level":"warn","ts":"2026-10-09T05:08:55.102512Z","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-09T05:08:55.102689Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.15: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.15:2380","--initial-cluster=instance=https://162.253.55.15:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.15:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.15: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-09T05:08:55.102858Z","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-09T05:08:55.102891Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-10-09T05:08:55.102936Z","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-09T05:08:55.104122Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"]} {"level":"info","ts":"2026-10-09T05:08:55.104286Z","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.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.15: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-09T05:08:55.109091Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.368348ms"} {"level":"info","ts":"2026-10-09T05:08:55.116752Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"337baeb286292d09","cluster-id":"3cc18487e1df591c"} {"level":"info","ts":"2026-10-09T05:08:55.116857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T05:08:55.116896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 0"} {"level":"info","ts":"2026-10-09T05:08:55.116922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 337baeb286292d09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T05:08:55.116941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became follower at term 1"} {"level":"info","ts":"2026-10-09T05:08:55.117002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"warn","ts":"2026-10-09T05:08:55.123513Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T05:08:55.125339Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T05:08:55.126793Z","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-09T05:08:55.129532Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"337baeb286292d09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T05:08:55.129796Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"337baeb286292d09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T05:08:55.129888Z","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-09T05:08:55.13002Z","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-09T05:08:55.130067Z","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-09T05:08:55.130994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 switched to configuration voters=(3709750799847992585)"} {"level":"info","ts":"2026-10-09T05:08:55.131168Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","added-peer-id":"337baeb286292d09","added-peer-peer-urls":["https://162.253.55.15:2380"]} {"level":"info","ts":"2026-10-09T05:08:55.13555Z","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-09T05:08:55.135716Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-10-09T05:08:55.135803Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.15:2380"} {"level":"info","ts":"2026-10-09T05:08:55.136166Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T05:08:55.136096Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"337baeb286292d09","initial-advertise-peer-urls":["https://162.253.55.15:2380"],"listen-peer-urls":["https://162.253.55.15:2380"],"advertise-client-urls":["https://162.253.55.15:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.15:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T05:08:55.821305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T05:08:55.82137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T05:08:55.821399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgPreVoteResp from 337baeb286292d09 at term 1"} {"level":"info","ts":"2026-10-09T05:08:55.821418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became candidate at term 2"} {"level":"info","ts":"2026-10-09T05:08:55.821425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 received MsgVoteResp from 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-10-09T05:08:55.821434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"337baeb286292d09 became leader at term 2"} {"level":"info","ts":"2026-10-09T05:08:55.821443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 337baeb286292d09 elected leader 337baeb286292d09 at term 2"} {"level":"info","ts":"2026-10-09T05:08:55.825271Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"337baeb286292d09","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.15:2379]}","request-path":"/0/members/337baeb286292d09/attributes","cluster-id":"3cc18487e1df591c","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T05:08:55.825337Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:08:55.825398Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:08:55.825507Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:08:55.825626Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T05:08:55.825658Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T05:08:55.826511Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3cc18487e1df591c","local-member-id":"337baeb286292d09","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:08:55.82659Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:08:55.826612Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:08:55.828273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.15:2379"} {"level":"info","ts":"2026-10-09T05:08:55.828761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-09T05:08:59.357956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.428964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:08:59.358097Z","caller":"traceutil/trace.go:171","msg":"trace[290093023] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"386.806671ms","start":"2026-10-09T05:08:58.971272Z","end":"2026-10-09T05:08:59.358078Z","steps":["trace[290093023] 'process raft request' (duration: 114.748664ms)","trace[290093023] 'compare' (duration: 271.307052ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:08:59.358158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:08:58.971258Z","time spent":"386.877882ms","remote":"127.0.0.1:57226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":792,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T05:08:59.542868Z","caller":"traceutil/trace.go:171","msg":"trace[381137621] linearizableReadLoop","detail":"{readStateIndex:161; appliedIndex:160; }","duration":"139.346474ms","start":"2026-10-09T05:08:59.403477Z","end":"2026-10-09T05:08:59.542823Z","steps":["trace[381137621] 'read index received' (duration: 134.975018ms)","trace[381137621] 'applied index is now lower than readState.Index' (duration: 4.369856ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:08:59.542886Z","caller":"traceutil/trace.go:171","msg":"trace[971187720] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"178.916089ms","start":"2026-10-09T05:08:59.363946Z","end":"2026-10-09T05:08:59.542862Z","steps":["trace[971187720] 'process raft request' (duration: 174.584204ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:08:59.543123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.615559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:08:59.543192Z","caller":"traceutil/trace.go:171","msg":"trace[370298368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:157; }","duration":"139.782582ms","start":"2026-10-09T05:08:59.403393Z","end":"2026-10-09T05:08:59.543176Z","steps":["trace[370298368] 'agreement among raft nodes before linearized reading' (duration: 139.600149ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:09:36.526571Z","caller":"traceutil/trace.go:171","msg":"trace[1285595943] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"100.161293ms","start":"2026-10-09T05:09:36.426387Z","end":"2026-10-09T05:09:36.526548Z","steps":["trace[1285595943] 'process raft request' (duration: 100.029191ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:09:45.015293Z","caller":"traceutil/trace.go:171","msg":"trace[1157533405] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"148.020734ms","start":"2026-10-09T05:09:44.867184Z","end":"2026-10-09T05:09:45.015205Z","steps":["trace[1157533405] 'read index received' (duration: 142.659706ms)","trace[1157533405] 'applied index is now lower than readState.Index' (duration: 5.359958ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:09:45.015495Z","caller":"traceutil/trace.go:171","msg":"trace[1353977295] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"219.443863ms","start":"2026-10-09T05:09:44.795979Z","end":"2026-10-09T05:09:45.015422Z","steps":["trace[1353977295] 'process raft request' (duration: 214.31902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:09:45.015543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.341819ms","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-09T05:09:45.015865Z","caller":"traceutil/trace.go:171","msg":"trace[429598781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"148.629094ms","start":"2026-10-09T05:09:44.867136Z","end":"2026-10-09T05:09:45.015765Z","steps":["trace[429598781] 'agreement among raft nodes before linearized reading' (duration: 148.268208ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:09:45.394378Z","caller":"traceutil/trace.go:171","msg":"trace[1682057978] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"361.144987ms","start":"2026-10-09T05:09:45.033203Z","end":"2026-10-09T05:09:45.394348Z","steps":["trace[1682057978] 'process raft request' (duration: 360.957814ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:09:45.394881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:09:45.033175Z","time spent":"361.28288ms","remote":"127.0.0.1:57064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:09:45.403881Z","caller":"traceutil/trace.go:171","msg":"trace[2009004991] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"315.661685ms","start":"2026-10-09T05:09:45.088193Z","end":"2026-10-09T05:09:45.403855Z","steps":["trace[2009004991] 'process raft request' (duration: 315.40578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:09:45.404031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:09:45.088174Z","time spent":"315.780436ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T05:10:02.198973Z","caller":"traceutil/trace.go:171","msg":"trace[27265240] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"155.615898ms","start":"2026-10-09T05:10:02.043329Z","end":"2026-10-09T05:10:02.198945Z","steps":["trace[27265240] 'process raft request' (duration: 155.312434ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:24.888977Z","caller":"traceutil/trace.go:171","msg":"trace[1139435170] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"133.520081ms","start":"2026-10-09T05:11:24.755424Z","end":"2026-10-09T05:11:24.888944Z","steps":["trace[1139435170] 'process raft request' (duration: 119.618194ms)","trace[1139435170] 'compare' (duration: 13.786911ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:11:24.909283Z","caller":"traceutil/trace.go:171","msg":"trace[19449179] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"119.648206ms","start":"2026-10-09T05:11:24.789599Z","end":"2026-10-09T05:11:24.909247Z","steps":["trace[19449179] 'process raft request' (duration: 119.382004ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:24.937738Z","caller":"traceutil/trace.go:171","msg":"trace[1552362850] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1988; }","duration":"115.215807ms","start":"2026-10-09T05:11:24.822496Z","end":"2026-10-09T05:11:24.937712Z","steps":["trace[1552362850] 'read index received' (duration: 52.55259ms)","trace[1552362850] 'applied index is now lower than readState.Index' (duration: 62.661837ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:11:24.938012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.50226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-94c4880b-5165-433e-8aba-5ab02e830ade\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-10-09T05:11:24.93808Z","caller":"traceutil/trace.go:171","msg":"trace[348040719] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-94c4880b-5165-433e-8aba-5ab02e830ade; range_end:; response_count:1; response_revision:1919; }","duration":"115.562843ms","start":"2026-10-09T05:11:24.82248Z","end":"2026-10-09T05:11:24.938043Z","steps":["trace[348040719] 'agreement among raft nodes before linearized reading' (duration: 115.348963ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:25.426499Z","caller":"traceutil/trace.go:171","msg":"trace[2014449931] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"253.274332ms","start":"2026-10-09T05:11:25.173199Z","end":"2026-10-09T05:11:25.426473Z","steps":["trace[2014449931] 'process raft request' (duration: 253.079703ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:25.613508Z","caller":"traceutil/trace.go:171","msg":"trace[881893416] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"126.972801ms","start":"2026-10-09T05:11:25.486509Z","end":"2026-10-09T05:11:25.613482Z","steps":["trace[881893416] 'read index received' (duration: 101.640612ms)","trace[881893416] 'applied index is now lower than readState.Index' (duration: 25.331359ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:11:25.613541Z","caller":"traceutil/trace.go:171","msg":"trace[1805827138] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"179.833973ms","start":"2026-10-09T05:11:25.433671Z","end":"2026-10-09T05:11:25.613505Z","steps":["trace[1805827138] 'process raft request' (duration: 154.515945ms)","trace[1805827138] 'compare' (duration: 25.100108ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:11:25.613724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.202402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-10-09T05:11:25.613768Z","caller":"traceutil/trace.go:171","msg":"trace[90813857] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1922; }","duration":"127.284646ms","start":"2026-10-09T05:11:25.486471Z","end":"2026-10-09T05:11:25.613755Z","steps":["trace[90813857] 'agreement among raft nodes before linearized reading' (duration: 127.127489ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:11:39.079886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:11:39.080025Z","caller":"traceutil/trace.go:171","msg":"trace[756802917] linearizableReadLoop","detail":"{readStateIndex:2105; appliedIndex:2104; }","duration":"213.354896ms","start":"2026-10-09T05:11:38.866648Z","end":"2026-10-09T05:11:39.080002Z","steps":["trace[756802917] 'read index received' (duration: 82.279416ms)","trace[756802917] 'applied index is now lower than readState.Index' (duration: 131.07388ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:11:39.080135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.468732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:11:39.080268Z","caller":"traceutil/trace.go:171","msg":"trace[2114903221] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2030; }","duration":"213.546415ms","start":"2026-10-09T05:11:38.866614Z","end":"2026-10-09T05:11:39.08016Z","steps":["trace[2114903221] 'agreement among raft nodes before linearized reading' (duration: 213.466991ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:39.08035Z","caller":"traceutil/trace.go:171","msg":"trace[1373409948] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"228.829169ms","start":"2026-10-09T05:11:38.851487Z","end":"2026-10-09T05:11:39.080316Z","steps":["trace[1373409948] 'process raft request' (duration: 97.440805ms)","trace[1373409948] 'compare' (duration: 130.729215ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:12:05.371517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.100815ms","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":"info","ts":"2026-10-09T05:12:05.371789Z","caller":"traceutil/trace.go:171","msg":"trace[1736911303] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2186; }","duration":"170.424928ms","start":"2026-10-09T05:12:05.201343Z","end":"2026-10-09T05:12:05.371768Z","steps":["trace[1736911303] 'count revisions from in-memory index tree' (duration: 170.014771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:12:05.371549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.385784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:12:05.371867Z","caller":"traceutil/trace.go:171","msg":"trace[1934181771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2186; }","duration":"134.747079ms","start":"2026-10-09T05:12:05.237107Z","end":"2026-10-09T05:12:05.371854Z","steps":["trace[1934181771] 'range keys from in-memory index tree' (duration: 134.25794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:12:05.37226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.122093ms","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-09T05:12:05.3723Z","caller":"traceutil/trace.go:171","msg":"trace[639363126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2186; }","duration":"144.185056ms","start":"2026-10-09T05:12:05.228104Z","end":"2026-10-09T05:12:05.37229Z","steps":["trace[639363126] 'range keys from in-memory index tree' (duration: 143.946826ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:05.862401Z","caller":"traceutil/trace.go:171","msg":"trace[1273695897] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"153.568911ms","start":"2026-10-09T05:12:05.708811Z","end":"2026-10-09T05:12:05.86238Z","steps":["trace[1273695897] 'process raft request' (duration: 153.419015ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:05.953669Z","caller":"traceutil/trace.go:171","msg":"trace[783856117] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"191.635085ms","start":"2026-10-09T05:12:05.761987Z","end":"2026-10-09T05:12:05.953622Z","steps":["trace[783856117] 'process raft request' (duration: 150.783213ms)","trace[783856117] 'compare' (duration: 40.666984ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:12:05.953692Z","caller":"traceutil/trace.go:171","msg":"trace[2092026642] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2269; }","duration":"133.413406ms","start":"2026-10-09T05:12:05.820221Z","end":"2026-10-09T05:12:05.953635Z","steps":["trace[2092026642] 'read index received' (duration: 41.970005ms)","trace[2092026642] 'applied index is now lower than readState.Index' (duration: 91.439701ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:12:05.953949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.710457ms","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-09T05:12:05.954049Z","caller":"traceutil/trace.go:171","msg":"trace[679518069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2190; }","duration":"133.855324ms","start":"2026-10-09T05:12:05.820175Z","end":"2026-10-09T05:12:05.95403Z","steps":["trace[679518069] 'agreement among raft nodes before linearized reading' (duration: 133.593693ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:06.299452Z","caller":"traceutil/trace.go:171","msg":"trace[165785455] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"113.029463ms","start":"2026-10-09T05:12:06.186395Z","end":"2026-10-09T05:12:06.299425Z","steps":["trace[165785455] 'process raft request' (duration: 112.828906ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:06.52638Z","caller":"traceutil/trace.go:171","msg":"trace[302746958] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"222.182356ms","start":"2026-10-09T05:12:06.304175Z","end":"2026-10-09T05:12:06.526358Z","steps":["trace[302746958] 'process raft request' (duration: 221.977908ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:12:06.526638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.75612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:12:06.526706Z","caller":"traceutil/trace.go:171","msg":"trace[130945777] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2274; }","duration":"147.471139ms","start":"2026-10-09T05:12:06.378838Z","end":"2026-10-09T05:12:06.526309Z","steps":["trace[130945777] 'read index received' (duration: 147.348914ms)","trace[130945777] 'applied index is now lower than readState.Index' (duration: 121.525µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:12:06.526719Z","caller":"traceutil/trace.go:171","msg":"trace[72619220] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2193; }","duration":"147.904946ms","start":"2026-10-09T05:12:06.378796Z","end":"2026-10-09T05:12:06.526701Z","steps":["trace[72619220] 'agreement among raft nodes before linearized reading' (duration: 147.683867ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:06.771835Z","caller":"traceutil/trace.go:171","msg":"trace[466373566] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"372.586708ms","start":"2026-10-09T05:12:06.399209Z","end":"2026-10-09T05:12:06.771796Z","steps":["trace[466373566] 'process raft request' (duration: 372.283317ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:06.771996Z","caller":"traceutil/trace.go:171","msg":"trace[748367036] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2275; }","duration":"189.359813ms","start":"2026-10-09T05:12:06.582584Z","end":"2026-10-09T05:12:06.771943Z","steps":["trace[748367036] 'read index received' (duration: 188.852794ms)","trace[748367036] 'applied index is now lower than readState.Index' (duration: 505.019µs)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:12:06.772123Z","caller":"traceutil/trace.go:171","msg":"trace[1133677747] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"340.88562ms","start":"2026-10-09T05:12:06.4312Z","end":"2026-10-09T05:12:06.772086Z","steps":["trace[1133677747] 'process raft request' (duration: 340.530446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:12:06.772205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:12:06.399183Z","time spent":"372.766485ms","remote":"127.0.0.1:57052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8559,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:12:06.772305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.699487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-10-09T05:12:06.772399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:12:06.431179Z","time spent":"341.046665ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:12:06.772434Z","caller":"traceutil/trace.go:171","msg":"trace[1544771523] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2195; }","duration":"189.860293ms","start":"2026-10-09T05:12:06.582534Z","end":"2026-10-09T05:12:06.772395Z","steps":["trace[1544771523] 'agreement among raft nodes before linearized reading' (duration: 189.668945ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:06.785637Z","caller":"traceutil/trace.go:171","msg":"trace[818570914] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"203.072345ms","start":"2026-10-09T05:12:06.582539Z","end":"2026-10-09T05:12:06.785612Z","steps":["trace[818570914] 'process raft request' (duration: 202.899438ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:13:05.28692Z","caller":"traceutil/trace.go:171","msg":"trace[477299666] linearizableReadLoop","detail":"{readStateIndex:2709; appliedIndex:2708; }","duration":"185.645837ms","start":"2026-10-09T05:13:05.101244Z","end":"2026-10-09T05:13:05.28689Z","steps":["trace[477299666] 'read index received' (duration: 183.224343ms)","trace[477299666] 'applied index is now lower than readState.Index' (duration: 2.420214ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:13:05.286996Z","caller":"traceutil/trace.go:171","msg":"trace[196697680] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"282.132499ms","start":"2026-10-09T05:13:05.004807Z","end":"2026-10-09T05:13:05.286939Z","steps":["trace[196697680] 'process raft request' (duration: 279.748806ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:13:05.287614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.100471ms","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-09T05:13:05.287692Z","caller":"traceutil/trace.go:171","msg":"trace[254085151] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2613; }","duration":"186.467442ms","start":"2026-10-09T05:13:05.101206Z","end":"2026-10-09T05:13:05.287673Z","steps":["trace[254085151] 'agreement among raft nodes before linearized reading' (duration: 185.834413ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:13:36.784481Z","caller":"traceutil/trace.go:171","msg":"trace[917397923] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"131.014671ms","start":"2026-10-09T05:13:36.653421Z","end":"2026-10-09T05:13:36.784436Z","steps":["trace[917397923] 'process raft request' (duration: 130.870097ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:17:55.40636Z","caller":"traceutil/trace.go:171","msg":"trace[1762413345] linearizableReadLoop","detail":"{readStateIndex:4942; appliedIndex:4940; }","duration":"132.830124ms","start":"2026-10-09T05:17:55.273508Z","end":"2026-10-09T05:17:55.406338Z","steps":["trace[1762413345] 'read index received' (duration: 16.464877ms)","trace[1762413345] 'applied index is now lower than readState.Index' (duration: 116.364257ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:17:55.406494Z","caller":"traceutil/trace.go:171","msg":"trace[1104227652] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"155.340013ms","start":"2026-10-09T05:17:55.251142Z","end":"2026-10-09T05:17:55.406482Z","steps":["trace[1104227652] 'process raft request' (duration: 131.720513ms)","trace[1104227652] 'compare' (duration: 23.344735ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:17:55.410525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.003752ms","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-09T05:17:55.41058Z","caller":"traceutil/trace.go:171","msg":"trace[999093256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4765; }","duration":"137.065593ms","start":"2026-10-09T05:17:55.273503Z","end":"2026-10-09T05:17:55.410568Z","steps":["trace[999093256] 'agreement among raft nodes before linearized reading' (duration: 133.208071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:17:57.631248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.550382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-10-09T05:17:57.631342Z","caller":"traceutil/trace.go:171","msg":"trace[433589099] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4813; }","duration":"120.661685ms","start":"2026-10-09T05:17:57.510661Z","end":"2026-10-09T05:17:57.631323Z","steps":["trace[433589099] 'range keys from in-memory index tree' (duration: 120.41841ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:17:58.643957Z","caller":"traceutil/trace.go:171","msg":"trace[1408947439] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"152.467725ms","start":"2026-10-09T05:17:58.491444Z","end":"2026-10-09T05:17:58.643912Z","steps":["trace[1408947439] 'process raft request' (duration: 138.401734ms)","trace[1408947439] 'compare' (duration: 13.893778ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:18:02.185797Z","caller":"traceutil/trace.go:171","msg":"trace[703533016] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"105.260713ms","start":"2026-10-09T05:18:02.080516Z","end":"2026-10-09T05:18:02.185776Z","steps":["trace[703533016] 'process raft request' (duration: 105.09414ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:18:02.591602Z","caller":"traceutil/trace.go:171","msg":"trace[1475386365] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"160.487007ms","start":"2026-10-09T05:18:02.431093Z","end":"2026-10-09T05:18:02.59158Z","steps":["trace[1475386365] 'process raft request' (duration: 160.328474ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:18:16.280301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.275817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-10-09T05:18:16.280369Z","caller":"traceutil/trace.go:171","msg":"trace[127462240] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5117; }","duration":"409.376789ms","start":"2026-10-09T05:18:15.870977Z","end":"2026-10-09T05:18:16.280354Z","steps":["trace[127462240] 'range keys from in-memory index tree' (duration: 409.019602ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:18:16.280396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:18:15.870964Z","time spent":"409.4254ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-10-09T05:18:16.280402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.711478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T05:18:16.280482Z","caller":"traceutil/trace.go:171","msg":"trace[564765758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5117; }","duration":"433.834159ms","start":"2026-10-09T05:18:15.846631Z","end":"2026-10-09T05:18:16.280466Z","steps":["trace[564765758] 'range keys from in-memory index tree' (duration: 433.552004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:18:16.280523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:18:15.846613Z","time spent":"433.902511ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-09T05:18:16.280595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.975756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:18:16.280699Z","caller":"traceutil/trace.go:171","msg":"trace[1615795920] linearizableReadLoop","detail":"{readStateIndex:5308; appliedIndex:5307; }","duration":"285.704001ms","start":"2026-10-09T05:18:15.99498Z","end":"2026-10-09T05:18:16.280684Z","steps":["trace[1615795920] 'read index received' (duration: 64.432µs)","trace[1615795920] 'applied index is now lower than readState.Index' (duration: 285.638469ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:18:16.280858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.889094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T05:18:16.280894Z","caller":"traceutil/trace.go:171","msg":"trace[650670281] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:5118; }","duration":"285.962686ms","start":"2026-10-09T05:18:15.994921Z","end":"2026-10-09T05:18:16.280884Z","steps":["trace[650670281] 'agreement among raft nodes before linearized reading' (duration: 285.832214ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:18:16.280812Z","caller":"traceutil/trace.go:171","msg":"trace[1518196092] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"388.882812ms","start":"2026-10-09T05:18:15.891878Z","end":"2026-10-09T05:18:16.28076Z","steps":["trace[1518196092] 'process raft request' (duration: 58.652951ms)","trace[1518196092] 'compare' (duration: 329.64358ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:18:16.281061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:18:15.891851Z","time spent":"389.091925ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:18:16.283903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.319931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-10-09T05:18:16.284014Z","caller":"traceutil/trace.go:171","msg":"trace[74676323] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5119; }","duration":"167.475574ms","start":"2026-10-09T05:18:16.116512Z","end":"2026-10-09T05:18:16.283987Z","steps":["trace[74676323] 'agreement among raft nodes before linearized reading' (duration: 167.165898ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:18:55.944767Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2888} {"level":"warn","ts":"2026-10-09T05:18:56.312412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.991752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:18:56.312568Z","caller":"traceutil/trace.go:171","msg":"trace[1953894979] linearizableReadLoop","detail":"{readStateIndex:5674; appliedIndex:5673; }","duration":"242.493648ms","start":"2026-10-09T05:18:56.070054Z","end":"2026-10-09T05:18:56.312548Z","steps":["trace[1953894979] 'read index received' (duration: 61.891µs)","trace[1953894979] 'applied index is now lower than readState.Index' (duration: 242.428837ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:18:56.312638Z","caller":"traceutil/trace.go:171","msg":"trace[854517017] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"331.427056ms","start":"2026-10-09T05:18:55.98115Z","end":"2026-10-09T05:18:56.312577Z","steps":["trace[854517017] 'process raft request' (duration: 12.17036ms)","trace[854517017] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 318.647276ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:18:56.312908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:18:55.981125Z","time spent":"331.644271ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:18:56.330595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.530794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-10-09T05:18:56.33068Z","caller":"traceutil/trace.go:171","msg":"trace[951689264] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5474; }","duration":"260.693547ms","start":"2026-10-09T05:18:56.069967Z","end":"2026-10-09T05:18:56.33066Z","steps":["trace[951689264] 'agreement among raft nodes before linearized reading' (duration: 242.737403ms)","trace[951689264] 'range keys from in-memory index tree' (duration: 16.964107ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:18:56.411223Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2888,"took":"465.022205ms","hash":1041847815,"current-db-size-bytes":54009856,"current-db-size":"54 MB","current-db-size-in-use-bytes":28712960,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-09T05:18:56.411323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1041847815,"revision":2888,"compact-revision":-1} {"level":"warn","ts":"2026-10-09T05:22:17.609437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.064447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:22:17.609941Z","caller":"traceutil/trace.go:171","msg":"trace[2017953682] linearizableReadLoop","detail":"{readStateIndex:7139; appliedIndex:7137; }","duration":"374.84104ms","start":"2026-10-09T05:22:17.23508Z","end":"2026-10-09T05:22:17.609921Z","steps":["trace[2017953682] 'read index received' (duration: 43.157844ms)","trace[2017953682] 'applied index is now lower than readState.Index' (duration: 331.682266ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:22:17.610077Z","caller":"traceutil/trace.go:171","msg":"trace[483912465] transaction","detail":"{read_only:false; response_revision:6892; number_of_response:1; }","duration":"364.449984ms","start":"2026-10-09T05:22:17.245618Z","end":"2026-10-09T05:22:17.610068Z","steps":["trace[483912465] 'process raft request' (duration: 364.162575ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:22:17.610202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:22:17.245593Z","time spent":"364.577668ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:22:17.61037Z","caller":"traceutil/trace.go:171","msg":"trace[784960557] transaction","detail":"{read_only:false; response_revision:6891; number_of_response:1; }","duration":"387.534848ms","start":"2026-10-09T05:22:17.22281Z","end":"2026-10-09T05:22:17.610345Z","steps":["trace[784960557] 'process raft request' (duration: 91.083488ms)","trace[784960557] 'compare' (duration: 294.624293ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:22:17.610516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.440298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:22:17.610598Z","caller":"traceutil/trace.go:171","msg":"trace[1430844073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6892; }","duration":"375.522041ms","start":"2026-10-09T05:22:17.235047Z","end":"2026-10-09T05:22:17.610569Z","steps":["trace[1430844073] 'agreement among raft nodes before linearized reading' (duration: 375.417348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:22:17.610673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:22:17.222796Z","time spent":"387.631871ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:22:17.610689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:22:17.235037Z","time spent":"375.645785ms","remote":"127.0.0.1:56890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-09T05:22:17.610938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.613177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:22:17.610998Z","caller":"traceutil/trace.go:171","msg":"trace[249107490] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:6892; }","duration":"290.72361ms","start":"2026-10-09T05:22:17.320267Z","end":"2026-10-09T05:22:17.61099Z","steps":["trace[249107490] 'agreement among raft nodes before linearized reading' (duration: 290.643258ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:22:17.611169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.955328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T05:22:17.611198Z","caller":"traceutil/trace.go:171","msg":"trace[1904480187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6892; }","duration":"160.035261ms","start":"2026-10-09T05:22:17.451156Z","end":"2026-10-09T05:22:17.611191Z","steps":["trace[1904480187] 'agreement among raft nodes before linearized reading' (duration: 159.928307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:22:17.61125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.69387ms","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-09T05:22:17.611282Z","caller":"traceutil/trace.go:171","msg":"trace[494428967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6892; }","duration":"283.760332ms","start":"2026-10-09T05:22:17.327512Z","end":"2026-10-09T05:22:17.611272Z","steps":["trace[494428967] 'agreement among raft nodes before linearized reading' (duration: 283.69225ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:22:17.806498Z","caller":"traceutil/trace.go:171","msg":"trace[1906216184] transaction","detail":"{read_only:false; response_revision:6893; number_of_response:1; }","duration":"189.409092ms","start":"2026-10-09T05:22:17.617057Z","end":"2026-10-09T05:22:17.806466Z","steps":["trace[1906216184] 'process raft request' (duration: 172.353227ms)","trace[1906216184] 'compare' (duration: 16.936251ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:22:17.864947Z","caller":"traceutil/trace.go:171","msg":"trace[696410442] linearizableReadLoop","detail":"{readStateIndex:7143; appliedIndex:7140; }","duration":"132.665402ms","start":"2026-10-09T05:22:17.732257Z","end":"2026-10-09T05:22:17.864922Z","steps":["trace[696410442] 'read index received' (duration: 57.075801ms)","trace[696410442] 'applied index is now lower than readState.Index' (duration: 75.588751ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:22:17.86517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.874769ms","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-10-09T05:22:17.865202Z","caller":"traceutil/trace.go:171","msg":"trace[1737069154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6895; }","duration":"132.967872ms","start":"2026-10-09T05:22:17.732225Z","end":"2026-10-09T05:22:17.865193Z","steps":["trace[1737069154] 'agreement among raft nodes before linearized reading' (duration: 132.792726ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:22:17.865646Z","caller":"traceutil/trace.go:171","msg":"trace[1706129075] transaction","detail":"{read_only:false; response_revision:6894; number_of_response:1; }","duration":"248.371433ms","start":"2026-10-09T05:22:17.617264Z","end":"2026-10-09T05:22:17.865635Z","steps":["trace[1706129075] 'process raft request' (duration: 247.466794ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:22:17.865789Z","caller":"traceutil/trace.go:171","msg":"trace[2104502309] transaction","detail":"{read_only:false; response_revision:6895; number_of_response:1; }","duration":"248.478766ms","start":"2026-10-09T05:22:17.617301Z","end":"2026-10-09T05:22:17.86578Z","steps":["trace[2104502309] 'process raft request' (duration: 247.554167ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:23:55.954547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5473} {"level":"info","ts":"2026-10-09T05:23:56.036918Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5473,"took":"80.92578ms","hash":1233002482,"current-db-size-bytes":54009856,"current-db-size":"54 MB","current-db-size-in-use-bytes":18665472,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-09T05:23:56.036992Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1233002482,"revision":5473,"compact-revision":2888}