{"level":"warn","ts":"2026-02-25T10:02:48.480577Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:02:48.480686Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.49: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.49:2380","--initial-cluster=instance=https://162.253.55.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.49: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-02-25T10:02:48.480779Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T10:02:48.480798Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-25T10:02:48.480827Z","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-02-25T10:02:48.481493Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"]} {"level":"info","ts":"2026-02-25T10:02:48.481604Z","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":16,"max-cpu-available":16,"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.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.49: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-02-25T10:02:48.486858Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.97881ms"} {"level":"info","ts":"2026-02-25T10:02:48.497523Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a9ba61e55fef5be","cluster-id":"9612db6e19ac6ce7"} {"level":"info","ts":"2026-02-25T10:02:48.497662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:02:48.497777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 0"} {"level":"info","ts":"2026-02-25T10:02:48.497835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a9ba61e55fef5be [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:02:48.49788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became follower at term 1"} {"level":"info","ts":"2026-02-25T10:02:48.498177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"warn","ts":"2026-02-25T10:02:48.50526Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:02:48.506962Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:02:48.508457Z","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-02-25T10:02:48.511219Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a9ba61e55fef5be","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:02:48.511523Z","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-02-25T10:02:48.511607Z","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-02-25T10:02:48.511501Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a9ba61e55fef5be","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:02:48.511668Z","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-02-25T10:02:48.51272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be switched to configuration voters=(7681916238632515006)"} {"level":"info","ts":"2026-02-25T10:02:48.512937Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","added-peer-id":"6a9ba61e55fef5be","added-peer-peer-urls":["https://162.253.55.49:2380"]} {"level":"info","ts":"2026-02-25T10:02:48.515801Z","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-02-25T10:02:48.515945Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-25T10:02:48.516Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.49:2380"} {"level":"info","ts":"2026-02-25T10:02:48.5162Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a9ba61e55fef5be","initial-advertise-peer-urls":["https://162.253.55.49:2380"],"listen-peer-urls":["https://162.253.55.49:2380"],"advertise-client-urls":["https://162.253.55.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:02:48.516265Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:02:48.900564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:02:48.900638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:02:48.900667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgPreVoteResp from 6a9ba61e55fef5be at term 1"} {"level":"info","ts":"2026-02-25T10:02:48.900684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:02:48.900689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be received MsgVoteResp from 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-25T10:02:48.900704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a9ba61e55fef5be became leader at term 2"} {"level":"info","ts":"2026-02-25T10:02:48.900713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a9ba61e55fef5be elected leader 6a9ba61e55fef5be at term 2"} {"level":"info","ts":"2026-02-25T10:02:48.957586Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:48.990344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:02:48.99034Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a9ba61e55fef5be","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.49:2379]}","request-path":"/0/members/6a9ba61e55fef5be/attributes","cluster-id":"9612db6e19ac6ce7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:02:48.990389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:02:48.990754Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:02:48.990856Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:02:48.992784Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.49:2379"} {"level":"info","ts":"2026-02-25T10:02:48.995439Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T10:02:49.032909Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9612db6e19ac6ce7","local-member-id":"6a9ba61e55fef5be","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:49.033307Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:49.033391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:02:51.20324Z","caller":"traceutil/trace.go:171","msg":"trace[499420123] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"142.315713ms","start":"2026-02-25T10:02:51.060887Z","end":"2026-02-25T10:02:51.203203Z","steps":["trace[499420123] 'process raft request' (duration: 72.61138ms)","trace[499420123] 'compare' (duration: 69.417498ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:02:51.976433Z","caller":"traceutil/trace.go:171","msg":"trace[1510254335] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"132.581138ms","start":"2026-02-25T10:02:51.843804Z","end":"2026-02-25T10:02:51.976385Z","steps":["trace[1510254335] 'process raft request' (duration: 68.947908ms)","trace[1510254335] 'compare' (duration: 63.423415ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:01.570764Z","caller":"traceutil/trace.go:171","msg":"trace[2106683457] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"439.611535ms","start":"2026-02-25T10:03:01.131132Z","end":"2026-02-25T10:03:01.570743Z","steps":["trace[2106683457] 'process raft request' (duration: 439.469322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:01.571247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:01.131114Z","time spent":"439.724038ms","remote":"127.0.0.1:52016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4396,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:01.573071Z","caller":"traceutil/trace.go:171","msg":"trace[1552009923] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:407; }","duration":"284.836101ms","start":"2026-02-25T10:03:01.288216Z","end":"2026-02-25T10:03:01.573052Z","steps":["trace[1552009923] 'read index received' (duration: 282.336891ms)","trace[1552009923] 'applied index is now lower than readState.Index' (duration: 2.4983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:01.573198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.982474ms","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-02-25T10:03:01.573267Z","caller":"traceutil/trace.go:171","msg":"trace[284916449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"285.119196ms","start":"2026-02-25T10:03:01.288137Z","end":"2026-02-25T10:03:01.573256Z","steps":["trace[284916449] 'agreement among raft nodes before linearized reading' (duration: 284.983614ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:01.573274Z","caller":"traceutil/trace.go:171","msg":"trace[629137534] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"426.46297ms","start":"2026-02-25T10:03:01.146776Z","end":"2026-02-25T10:03:01.573239Z","steps":["trace[629137534] 'process raft request' (duration: 426.185485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:01.573436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:01.14674Z","time spent":"426.608393ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:06.810174Z","caller":"traceutil/trace.go:171","msg":"trace[502010701] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"117.025505ms","start":"2026-02-25T10:03:06.693112Z","end":"2026-02-25T10:03:06.810138Z","steps":["trace[502010701] 'process raft request' (duration: 116.82195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:07.811477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.022033ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:07.811688Z","caller":"traceutil/trace.go:171","msg":"trace[912336851] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:412; }","duration":"272.274028ms","start":"2026-02-25T10:03:07.539381Z","end":"2026-02-25T10:03:07.811655Z","steps":["trace[912336851] 'range keys from in-memory index tree' (duration: 271.988312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:07.812422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.824456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:07.812585Z","caller":"traceutil/trace.go:171","msg":"trace[851415547] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"996.53182ms","start":"2026-02-25T10:03:06.816033Z","end":"2026-02-25T10:03:07.812564Z","steps":["trace[851415547] 'process raft request' (duration: 417.914728ms)","trace[851415547] 'compare' (duration: 577.641712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:07.812709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:06.816019Z","time spent":"996.633321ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:07.813328Z","caller":"traceutil/trace.go:171","msg":"trace[1815301284] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:426; }","duration":"631.877344ms","start":"2026-02-25T10:03:07.181243Z","end":"2026-02-25T10:03:07.81312Z","steps":["trace[1815301284] 'read index received' (duration: 52.715651ms)","trace[1815301284] 'applied index is now lower than readState.Index' (duration: 579.159323ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:07.813508Z","caller":"traceutil/trace.go:171","msg":"trace[326329100] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"990.302896ms","start":"2026-02-25T10:03:06.823168Z","end":"2026-02-25T10:03:07.813471Z","steps":["trace[326329100] 'process raft request' (duration: 989.778044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:07.813587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.453654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:03:07.813627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.320383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:07.813656Z","caller":"traceutil/trace.go:171","msg":"trace[993920530] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"206.570597ms","start":"2026-02-25T10:03:07.607065Z","end":"2026-02-25T10:03:07.813636Z","steps":["trace[993920530] 'agreement among raft nodes before linearized reading' (duration: 206.440634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:07.813692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:06.823147Z","time spent":"990.451238ms","remote":"127.0.0.1:52016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:07.813671Z","caller":"traceutil/trace.go:171","msg":"trace[713705720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"632.424985ms","start":"2026-02-25T10:03:07.181232Z","end":"2026-02-25T10:03:07.813657Z","steps":["trace[713705720] 'agreement among raft nodes before linearized reading' (duration: 632.221041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:07.813796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:07.181161Z","time spent":"632.622759ms","remote":"127.0.0.1:51856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:03:08.006318Z","caller":"traceutil/trace.go:171","msg":"trace[654972696] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"125.187468ms","start":"2026-02-25T10:03:07.881096Z","end":"2026-02-25T10:03:08.006284Z","steps":["trace[654972696] 'process raft request' (duration: 76.596111ms)","trace[654972696] 'compare' (duration: 48.308642ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:08.00638Z","caller":"traceutil/trace.go:171","msg":"trace[1738213645] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"116.964093ms","start":"2026-02-25T10:03:07.889387Z","end":"2026-02-25T10:03:08.006351Z","steps":["trace[1738213645] 'process raft request' (duration: 116.900011ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:08.00646Z","caller":"traceutil/trace.go:171","msg":"trace[75810141] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"118.2338ms","start":"2026-02-25T10:03:07.888183Z","end":"2026-02-25T10:03:08.006417Z","steps":["trace[75810141] 'process raft request' (duration: 118.051165ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:08.00702Z","caller":"traceutil/trace.go:171","msg":"trace[1621832633] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"119.849342ms","start":"2026-02-25T10:03:07.886515Z","end":"2026-02-25T10:03:08.006365Z","steps":["trace[1621832633] 'process raft request' (duration: 119.652107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:13.331068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:12.999697Z","time spent":"331.366717ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T10:03:13.434348Z","caller":"traceutil/trace.go:171","msg":"trace[1847641846] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"102.175455ms","start":"2026-02-25T10:03:13.332143Z","end":"2026-02-25T10:03:13.434318Z","steps":["trace[1847641846] 'process raft request' (duration: 85.296726ms)","trace[1847641846] 'compare' (duration: 16.718476ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:18.519234Z","caller":"traceutil/trace.go:171","msg":"trace[354138287] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"102.749586ms","start":"2026-02-25T10:03:18.41646Z","end":"2026-02-25T10:03:18.51921Z","steps":["trace[354138287] 'process raft request' (duration: 102.606394ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:18.557723Z","caller":"traceutil/trace.go:171","msg":"trace[66008211] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"141.125149ms","start":"2026-02-25T10:03:18.416581Z","end":"2026-02-25T10:03:18.557706Z","steps":["trace[66008211] 'process raft request' (duration: 139.054487ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:22.434956Z","caller":"traceutil/trace.go:171","msg":"trace[563600965] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"289.961874ms","start":"2026-02-25T10:03:22.14497Z","end":"2026-02-25T10:03:22.434932Z","steps":["trace[563600965] 'process raft request' (duration: 289.803581ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:28.77584Z","caller":"traceutil/trace.go:171","msg":"trace[462838467] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"201.448424ms","start":"2026-02-25T10:03:28.574369Z","end":"2026-02-25T10:03:28.775817Z","steps":["trace[462838467] 'read index received' (duration: 140.548528ms)","trace[462838467] 'applied index is now lower than readState.Index' (duration: 60.898896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:28.776068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.683718ms","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-02-25T10:03:28.7761Z","caller":"traceutil/trace.go:171","msg":"trace[1782465934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"201.77297ms","start":"2026-02-25T10:03:28.574318Z","end":"2026-02-25T10:03:28.776091Z","steps":["trace[1782465934] 'agreement among raft nodes before linearized reading' (duration: 201.627067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:28.776144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.93175ms","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-02-25T10:03:28.77623Z","caller":"traceutil/trace.go:171","msg":"trace[999309725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"173.083063ms","start":"2026-02-25T10:03:28.603126Z","end":"2026-02-25T10:03:28.776209Z","steps":["trace[999309725] 'agreement among raft nodes before linearized reading' (duration: 172.840678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:29.717542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17707762945415394999,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:03:29.776071Z","caller":"traceutil/trace.go:171","msg":"trace[1441585739] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"996.082112ms","start":"2026-02-25T10:03:28.779964Z","end":"2026-02-25T10:03:29.776046Z","steps":["trace[1441585739] 'process raft request' (duration: 995.913799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:29.776276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:28.779942Z","time spent":"996.205894ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:29.860708Z","caller":"traceutil/trace.go:171","msg":"trace[1498245127] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"643.846714ms","start":"2026-02-25T10:03:29.216837Z","end":"2026-02-25T10:03:29.860684Z","steps":["trace[1498245127] 'read index received' (duration: 559.038868ms)","trace[1498245127] 'applied index is now lower than readState.Index' (duration: 84.806756ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:29.860782Z","caller":"traceutil/trace.go:171","msg":"trace[1791044404] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.080613102s","start":"2026-02-25T10:03:28.780131Z","end":"2026-02-25T10:03:29.860744Z","steps":["trace[1791044404] 'process raft request' (duration: 1.010644795s)","trace[1791044404] 'compare' (duration: 69.798274ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:29.860976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:28.780108Z","time spent":"1.080765806s","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:29.860964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.09184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:29.861163Z","caller":"traceutil/trace.go:171","msg":"trace[1378993687] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:556; }","duration":"644.330394ms","start":"2026-02-25T10:03:29.216808Z","end":"2026-02-25T10:03:29.861138Z","steps":["trace[1378993687] 'agreement among raft nodes before linearized reading' (duration: 644.049389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:29.861227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:29.216793Z","time spent":"644.417476ms","remote":"127.0.0.1:51934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T10:03:30.684916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.62073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:30.68539Z","caller":"traceutil/trace.go:171","msg":"trace[2039586823] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"757.837757ms","start":"2026-02-25T10:03:29.927466Z","end":"2026-02-25T10:03:30.685304Z","steps":["trace[2039586823] 'process raft request' (duration: 757.572562ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:30.685357Z","caller":"traceutil/trace.go:171","msg":"trace[209608306] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"760.933729ms","start":"2026-02-25T10:03:29.924365Z","end":"2026-02-25T10:03:30.685299Z","steps":["trace[209608306] 'process raft request' (duration: 196.430832ms)","trace[209608306] 'compare' (duration: 563.416676ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:30.685458Z","caller":"traceutil/trace.go:171","msg":"trace[1911255442] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"398.62724ms","start":"2026-02-25T10:03:30.286779Z","end":"2026-02-25T10:03:30.685406Z","steps":["trace[1911255442] 'read index received' (duration: 3.594032ms)","trace[1911255442] 'applied index is now lower than readState.Index' (duration: 395.028108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:30.685513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:29.924329Z","time spent":"761.111993ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:30.685531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:29.927435Z","time spent":"758.001771ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:03:30.685581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.800673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:30.685649Z","caller":"traceutil/trace.go:171","msg":"trace[2002606991] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:558; }","duration":"398.924596ms","start":"2026-02-25T10:03:30.286702Z","end":"2026-02-25T10:03:30.685627Z","steps":["trace[2002606991] 'agreement among raft nodes before linearized reading' (duration: 398.800913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:30.685702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:30.286671Z","time spent":"399.021318ms","remote":"127.0.0.1:51934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T10:03:31.304696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.456528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:03:31.304886Z","caller":"traceutil/trace.go:171","msg":"trace[235837333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"506.685084ms","start":"2026-02-25T10:03:30.798176Z","end":"2026-02-25T10:03:31.304861Z","steps":["trace[235837333] 'range keys from in-memory index tree' (duration: 506.322506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:31.304989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:30.798156Z","time spent":"506.816917ms","remote":"127.0.0.1:51856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:03:32.584644Z","caller":"traceutil/trace.go:171","msg":"trace[1043953730] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"252.928808ms","start":"2026-02-25T10:03:32.331684Z","end":"2026-02-25T10:03:32.584613Z","steps":["trace[1043953730] 'process raft request' (duration: 248.790125ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:32.594365Z","caller":"traceutil/trace.go:171","msg":"trace[3389382] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"261.95712ms","start":"2026-02-25T10:03:32.332381Z","end":"2026-02-25T10:03:32.594338Z","steps":["trace[3389382] 'process raft request' (duration: 261.762107ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:32.594383Z","caller":"traceutil/trace.go:171","msg":"trace[575253667] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"261.212366ms","start":"2026-02-25T10:03:32.333156Z","end":"2026-02-25T10:03:32.594368Z","steps":["trace[575253667] 'process raft request' (duration: 261.118714ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:32.59456Z","caller":"traceutil/trace.go:171","msg":"trace[861903885] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"259.652255ms","start":"2026-02-25T10:03:32.3349Z","end":"2026-02-25T10:03:32.594552Z","steps":["trace[861903885] 'process raft request' (duration: 259.4295ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:32.59474Z","caller":"traceutil/trace.go:171","msg":"trace[385441761] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"259.619784ms","start":"2026-02-25T10:03:32.33511Z","end":"2026-02-25T10:03:32.59473Z","steps":["trace[385441761] 'process raft request' (duration: 259.40456ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:32.59502Z","caller":"traceutil/trace.go:171","msg":"trace[1822979392] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"252.036971ms","start":"2026-02-25T10:03:32.342973Z","end":"2026-02-25T10:03:32.59501Z","steps":["trace[1822979392] 'process raft request' (duration: 251.725965ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:32.595116Z","caller":"traceutil/trace.go:171","msg":"trace[19682882] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"193.927861ms","start":"2026-02-25T10:03:32.401161Z","end":"2026-02-25T10:03:32.595088Z","steps":["trace[19682882] 'process raft request' (duration: 193.730057ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:33.53785Z","caller":"traceutil/trace.go:171","msg":"trace[1220913926] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"187.826759ms","start":"2026-02-25T10:03:33.349992Z","end":"2026-02-25T10:03:33.537818Z","steps":["trace[1220913926] 'process raft request' (duration: 187.624265ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:49.615475Z","caller":"traceutil/trace.go:171","msg":"trace[484472577] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"216.67209ms","start":"2026-02-25T10:03:49.398766Z","end":"2026-02-25T10:03:49.615438Z","steps":["trace[484472577] 'process raft request' (duration: 208.922464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:50.471305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.418565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:03:50.471751Z","caller":"traceutil/trace.go:171","msg":"trace[141685601] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"1.072804844s","start":"2026-02-25T10:03:49.398921Z","end":"2026-02-25T10:03:50.471726Z","steps":["trace[141685601] 'process raft request' (duration: 847.866709ms)","trace[141685601] 'compare' (duration: 224.291013ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:03:50.471875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:49.398885Z","time spent":"1.072928197s","remote":"127.0.0.1:52010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1080,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:50.471755Z","caller":"traceutil/trace.go:171","msg":"trace[1172630533] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"599.139495ms","start":"2026-02-25T10:03:49.872597Z","end":"2026-02-25T10:03:50.471736Z","steps":["trace[1172630533] 'read index received' (duration: 374.174419ms)","trace[1172630533] 'applied index is now lower than readState.Index' (duration: 224.963976ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:03:50.471874Z","caller":"traceutil/trace.go:171","msg":"trace[128404659] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"1.072303713s","start":"2026-02-25T10:03:49.399509Z","end":"2026-02-25T10:03:50.471812Z","steps":["trace[128404659] 'process raft request' (duration: 1.072107949s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:50.471957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.358459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T10:03:50.472419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.638955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T10:03:50.472427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.614525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:03:50.472436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:49.399478Z","time spent":"1.072811995s","remote":"127.0.0.1:52328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:03:50.472528Z","caller":"traceutil/trace.go:171","msg":"trace[437847700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"514.763286ms","start":"2026-02-25T10:03:49.957724Z","end":"2026-02-25T10:03:50.472487Z","steps":["trace[437847700] 'agreement among raft nodes before linearized reading' (duration: 514.538422ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:03:50.472555Z","caller":"traceutil/trace.go:171","msg":"trace[206694614] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:922; }","duration":"440.779158ms","start":"2026-02-25T10:03:50.031757Z","end":"2026-02-25T10:03:50.472536Z","steps":["trace[206694614] 'agreement among raft nodes before linearized reading' (duration: 440.519113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:50.47258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:49.957702Z","time spent":"514.867109ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:03:50.472599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:50.031709Z","time spent":"440.88062ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:03:50.472594Z","caller":"traceutil/trace.go:171","msg":"trace[1681081896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"599.898781ms","start":"2026-02-25T10:03:49.872554Z","end":"2026-02-25T10:03:50.472453Z","steps":["trace[1681081896] 'agreement among raft nodes before linearized reading' (duration: 599.242397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:03:50.472786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:03:49.872533Z","time spent":"600.216717ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:04:13.082466Z","caller":"traceutil/trace.go:171","msg":"trace[277569807] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"165.158452ms","start":"2026-02-25T10:04:12.917279Z","end":"2026-02-25T10:04:13.082438Z","steps":["trace[277569807] 'process raft request' (duration: 164.985158ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:13.088057Z","caller":"traceutil/trace.go:171","msg":"trace[1947585936] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"167.338725ms","start":"2026-02-25T10:04:12.920671Z","end":"2026-02-25T10:04:13.08801Z","steps":["trace[1947585936] 'process raft request' (duration: 167.177732ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:32.978317Z","caller":"traceutil/trace.go:171","msg":"trace[364609497] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"261.638444ms","start":"2026-02-25T10:04:32.71665Z","end":"2026-02-25T10:04:32.978288Z","steps":["trace[364609497] 'process raft request' (duration: 261.44011ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:33.073919Z","caller":"traceutil/trace.go:171","msg":"trace[1058358989] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1510; }","duration":"275.824694ms","start":"2026-02-25T10:04:32.798071Z","end":"2026-02-25T10:04:33.073896Z","steps":["trace[1058358989] 'read index received' (duration: 180.116438ms)","trace[1058358989] 'applied index is now lower than readState.Index' (duration: 95.707026ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:04:33.074122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.013007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:04:33.074131Z","caller":"traceutil/trace.go:171","msg":"trace[1296130174] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"356.177356ms","start":"2026-02-25T10:04:32.717799Z","end":"2026-02-25T10:04:33.073976Z","steps":["trace[1296130174] 'process raft request' (duration: 354.479452ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:33.07421Z","caller":"traceutil/trace.go:171","msg":"trace[151502750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1451; }","duration":"276.185061ms","start":"2026-02-25T10:04:32.798002Z","end":"2026-02-25T10:04:33.074187Z","steps":["trace[151502750] 'agreement among raft nodes before linearized reading' (duration: 276.017438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.074348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:04:32.717785Z","time spent":"356.447321ms","remote":"127.0.0.1:52118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:04:33.074368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.282083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:146710"} {"level":"info","ts":"2026-02-25T10:04:33.074502Z","caller":"traceutil/trace.go:171","msg":"trace[634933757] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:1451; }","duration":"276.480298ms","start":"2026-02-25T10:04:32.797991Z","end":"2026-02-25T10:04:33.074471Z","steps":["trace[634933757] 'agreement among raft nodes before linearized reading' (duration: 275.997269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:04:33.319572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.821241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T10:04:33.319631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.525546ms","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-02-25T10:04:33.319683Z","caller":"traceutil/trace.go:171","msg":"trace[1983958241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1452; }","duration":"135.989574ms","start":"2026-02-25T10:04:33.18367Z","end":"2026-02-25T10:04:33.31966Z","steps":["trace[1983958241] 'range keys from in-memory index tree' (duration: 135.631087ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:33.319732Z","caller":"traceutil/trace.go:171","msg":"trace[329404331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1452; }","duration":"133.695588ms","start":"2026-02-25T10:04:33.186012Z","end":"2026-02-25T10:04:33.319708Z","steps":["trace[329404331] 'range keys from in-memory index tree' (duration: 133.273641ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:33.320397Z","caller":"traceutil/trace.go:171","msg":"trace[1335470496] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1452; }","duration":"119.019848ms","start":"2026-02-25T10:04:33.201354Z","end":"2026-02-25T10:04:33.320373Z","steps":["trace[1335470496] 'process raft request' (duration: 118.873785ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:04:33.331321Z","caller":"traceutil/trace.go:171","msg":"trace[75500605] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1452; }","duration":"129.671578ms","start":"2026-02-25T10:04:33.201632Z","end":"2026-02-25T10:04:33.331303Z","steps":["trace[75500605] 'process raft request' (duration: 129.422313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:06.540172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:06.08075Z","time spent":"459.413451ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T10:05:06.857514Z","caller":"traceutil/trace.go:171","msg":"trace[615229658] linearizableReadLoop","detail":"{readStateIndex:1692; appliedIndex:1690; }","duration":"497.73426ms","start":"2026-02-25T10:05:06.359751Z","end":"2026-02-25T10:05:06.857485Z","steps":["trace[615229658] 'read index received' (duration: 180.199619ms)","trace[615229658] 'applied index is now lower than readState.Index' (duration: 317.533321ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:05:06.857609Z","caller":"traceutil/trace.go:171","msg":"trace[33048427] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"773.827641ms","start":"2026-02-25T10:05:06.08374Z","end":"2026-02-25T10:05:06.857567Z","steps":["trace[33048427] 'process raft request' (duration: 773.497614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:06.85782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:06.083714Z","time spent":"773.982083ms","remote":"127.0.0.1:52016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4756,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:05:06.857946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.164689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T10:05:06.858007Z","caller":"traceutil/trace.go:171","msg":"trace[1084593257] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1619; }","duration":"498.301342ms","start":"2026-02-25T10:05:06.359689Z","end":"2026-02-25T10:05:06.85799Z","steps":["trace[1084593257] 'agreement among raft nodes before linearized reading' (duration: 497.963375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:06.858052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:06.359662Z","time spent":"498.380663ms","remote":"127.0.0.1:45706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3898,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T10:05:06.858444Z","caller":"traceutil/trace.go:171","msg":"trace[2011169503] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"316.659593ms","start":"2026-02-25T10:05:06.541747Z","end":"2026-02-25T10:05:06.858407Z","steps":["trace[2011169503] 'process raft request' (duration: 316.447869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:05:06.858701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:05:06.541713Z","time spent":"316.864727ms","remote":"127.0.0.1:51916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:12:49.063696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2180} {"level":"info","ts":"2026-02-25T10:12:49.125211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2180,"took":"60.361538ms","hash":4263779913,"current-db-size-bytes":14499840,"current-db-size":"14 MB","current-db-size-in-use-bytes":4374528,"current-db-size-in-use":"4.4 MB"} {"level":"info","ts":"2026-02-25T10:12:49.125311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4263779913,"revision":2180,"compact-revision":-1} {"level":"info","ts":"2026-02-25T10:17:49.070633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3109} {"level":"info","ts":"2026-02-25T10:17:49.091829Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3109,"took":"20.463535ms","hash":2272385007,"current-db-size-bytes":14499840,"current-db-size":"14 MB","current-db-size-in-use-bytes":4734976,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T10:17:49.09189Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2272385007,"revision":3109,"compact-revision":2180}