{"level":"warn","ts":"2026-04-13T06:08:43.444986Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T06:08:43.445078Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-04-13T06:08:43.445164Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T06:08:43.445175Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-13T06:08:43.445202Z","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-04-13T06:08:43.445833Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-13T06:08:43.44591Z","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.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-04-13T06:08:43.453382Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.22613ms"} {"level":"info","ts":"2026-04-13T06:08:43.469201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-13T06:08:43.469293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T06:08:43.469316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-13T06:08:43.469327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T06:08:43.469335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-13T06:08:43.469377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-13T06:08:43.492299Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T06:08:43.495872Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T06:08:43.497082Z","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-04-13T06:08:43.501539Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T06:08:43.501844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T06:08:43.501861Z","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-04-13T06:08:43.502095Z","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-04-13T06:08:43.502132Z","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-04-13T06:08:43.504729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-13T06:08:43.505398Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-13T06:08:43.508268Z","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-04-13T06:08:43.508537Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-13T06:08:43.5086Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-13T06:08:43.50865Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T06:08:43.508713Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T06:08:43.569953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T06:08:43.570053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T06:08:43.570156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-13T06:08:43.570186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-13T06:08:43.570197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-13T06:08:43.570249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-13T06:08:43.570264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-13T06:08:43.571543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:08:43.572313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T06:08:43.572311Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T06:08:43.57245Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T06:08:43.572738Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T06:08:43.57276Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:08:43.572796Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T06:08:43.572991Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:08:43.573056Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T06:08:43.576518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-13T06:08:43.576514Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-13T06:08:55.094419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.619993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-zrjhz\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-04-13T06:08:55.094573Z","caller":"traceutil/trace.go:171","msg":"trace[607024543] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-zrjhz; range_end:; response_count:1; response_revision:354; }","duration":"256.824558ms","start":"2026-04-13T06:08:54.837728Z","end":"2026-04-13T06:08:55.094553Z","steps":["trace[607024543] 'range keys from in-memory index tree' (duration: 256.50839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:03.08705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:03.587708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:04.087869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:04.586532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000250702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:04.586615Z","caller":"traceutil/trace.go:171","msg":"trace[35879485] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000345015s","start":"2026-04-13T06:09:02.586249Z","end":"2026-04-13T06:09:04.586594Z","steps":["trace[35879485] 'agreement among raft nodes before linearized reading' (duration: 2.000248232s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:04.586661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:02.586169Z","time spent":"2.000484308s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:04.588397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:05.089239Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:05.589347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:06.090425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:06.587529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000029325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:06.58761Z","caller":"traceutil/trace.go:171","msg":"trace[1808306435] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000148539s","start":"2026-04-13T06:09:04.587441Z","end":"2026-04-13T06:09:06.58759Z","steps":["trace[1808306435] 'agreement among raft nodes before linearized reading' (duration: 2.000054176s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:06.587651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:04.587426Z","time spent":"2.000217631s","remote":"127.0.0.1:41222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:06.591181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:07.091651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:07.591859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:08.092391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:08.593434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:08.596681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999986205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:08.596767Z","caller":"traceutil/trace.go:171","msg":"trace[1362015266] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000157929s","start":"2026-04-13T06:09:06.59659Z","end":"2026-04-13T06:09:08.596748Z","steps":["trace[1362015266] 'agreement among raft nodes before linearized reading' (duration: 2.000038515s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:08.596815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.596566Z","time spent":"2.000240243s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:08.791297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999022275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:08.791458Z","caller":"traceutil/trace.go:171","msg":"trace[2067598505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999207581s","start":"2026-04-13T06:09:03.792193Z","end":"2026-04-13T06:09:08.7914Z","steps":["trace[2067598505] 'agreement among raft nodes before linearized reading' (duration: 4.999054477s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:08.791551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:03.792173Z","time spent":"4.999361405s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/13 06:09:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:08.838513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99920173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:08.838587Z","caller":"traceutil/trace.go:171","msg":"trace[440813203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999313803s","start":"2026-04-13T06:09:03.839256Z","end":"2026-04-13T06:09:08.83857Z","steps":["trace[440813203] 'agreement among raft nodes before linearized reading' (duration: 4.999217151s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:08.838626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:03.839243Z","time spent":"4.999374086s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/13 06:09:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:09.093983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530120,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:09.450894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:02.450684Z","time spent":"7.000203053s","remote":"127.0.0.1:41380","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-13T06:09:09.513771Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4d269d8574e3cee0","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-04-13T06:09:09.587177Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-04-13T06:09:10.087864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:10.58903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:10.597294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999888142s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-13T06:09:10.597395Z","caller":"traceutil/trace.go:171","msg":"trace[1941657272] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000022385s","start":"2026-04-13T06:09:08.597345Z","end":"2026-04-13T06:09:10.597367Z","steps":["trace[1941657272] 'agreement among raft nodes before linearized reading' (duration: 1.999912422s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:10.597442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:08.59733Z","time spent":"2.000102728s","remote":"127.0.0.1:41222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:09:10 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:11.004504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999682236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:11.004588Z","caller":"traceutil/trace.go:171","msg":"trace[1745629047] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999853771s","start":"2026-04-13T06:09:09.004718Z","end":"2026-04-13T06:09:11.004572Z","steps":["trace[1745629047] 'agreement among raft nodes before linearized reading' (duration: 1.999736968s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:11.004626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.004682Z","time spent":"1.999935492s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:09:11 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:11.089754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:11.590746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:12.091667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:12.592193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782676023407530127,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T06:09:12.605301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000256541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:12.605382Z","caller":"traceutil/trace.go:171","msg":"trace[634164019] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000410146s","start":"2026-04-13T06:09:10.604952Z","end":"2026-04-13T06:09:12.605362Z","steps":["trace[634164019] 'agreement among raft nodes before linearized reading' (duration: 2.000311083s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:12.605427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:10.604901Z","time spent":"2.000517259s","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/13 06:09:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:12.792512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.001069304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-13T06:09:12.792591Z","caller":"traceutil/trace.go:171","msg":"trace[1471781586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.001218328s","start":"2026-04-13T06:09:02.791354Z","end":"2026-04-13T06:09:12.792572Z","steps":["trace[1471781586] 'agreement among raft nodes before linearized reading' (duration: 10.001119476s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:12.792636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:02.791322Z","time spent":"10.00130512s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/04/13 06:09:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-13T06:09:12.862181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"10.411348219s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T06:09:13.047128Z","caller":"traceutil/trace.go:171","msg":"trace[1555575024] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"6.939757684s","start":"2026-04-13T06:09:06.107342Z","end":"2026-04-13T06:09:13.047099Z","steps":["trace[1555575024] 'process raft request' (duration: 6.939566899s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.047665Z","caller":"traceutil/trace.go:171","msg":"trace[2043671122] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"6.456029783s","start":"2026-04-13T06:09:06.59159Z","end":"2026-04-13T06:09:13.04762Z","steps":["trace[2043671122] 'process raft request' (duration: 6.455445576s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.047893Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"124.964µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-04-13T06:09:13.047931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.591571Z","time spent":"6.456284239s","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T06:09:13.048058Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4d269d8574e3cee0","error":"lease not found"} {"level":"warn","ts":"2026-04-13T06:09:13.048069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:06.107323Z","time spent":"6.939908669s","remote":"127.0.0.1:41466","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-04-13T06:09:13.048251Z","caller":"traceutil/trace.go:171","msg":"trace[1615859071] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"4.010551901s","start":"2026-04-13T06:09:09.03736Z","end":"2026-04-13T06:09:13.047912Z","steps":["trace[1615859071] 'process raft request' (duration: 4.010162511s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.048455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.037334Z","time spent":"4.011058945s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:09:13.111952Z","caller":"traceutil/trace.go:171","msg":"trace[1794669532] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:420; }","duration":"3.524655728s","start":"2026-04-13T06:09:09.587247Z","end":"2026-04-13T06:09:13.111903Z","steps":["trace[1794669532] 'read index received' (duration: 3.275212564s)","trace[1794669532] 'applied index is now lower than readState.Index' (duration: 249.441774ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:09:13.112106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.932002958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:09:13.112125Z","caller":"traceutil/trace.go:171","msg":"trace[1993166181] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"317.34623ms","start":"2026-04-13T06:09:12.794736Z","end":"2026-04-13T06:09:13.112082Z","steps":["trace[1993166181] 'process raft request' (duration: 316.948648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.112207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.559089444s","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-04-13T06:09:13.112201Z","caller":"traceutil/trace.go:171","msg":"trace[806023005] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:0; response_revision:414; }","duration":"5.932156832s","start":"2026-04-13T06:09:07.180023Z","end":"2026-04-13T06:09:13.11218Z","steps":["trace[806023005] 'agreement among raft nodes before linearized reading' (duration: 5.931973047s)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:13.112258Z","caller":"traceutil/trace.go:171","msg":"trace[1519845033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"9.559211018s","start":"2026-04-13T06:09:03.553035Z","end":"2026-04-13T06:09:13.112246Z","steps":["trace[1519845033] 'agreement among raft nodes before linearized reading' (duration: 9.559080143s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.112272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:07.180003Z","time spent":"5.932260425s","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-04-13T06:09:13.112269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:12.794714Z","time spent":"317.488463ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:09:13.112307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:03.553017Z","time spent":"9.559277069s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T06:09:13.112337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.338447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"warn","ts":"2026-04-13T06:09:13.112376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.46605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:09:13.112405Z","caller":"traceutil/trace.go:171","msg":"trace[462952859] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:414; }","duration":"318.45646ms","start":"2026-04-13T06:09:12.793928Z","end":"2026-04-13T06:09:13.112385Z","steps":["trace[462952859] 'agreement among raft nodes before linearized reading' (duration: 318.278735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.112461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:12.793911Z","time spent":"318.536322ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12696,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-13T06:09:13.112466Z","caller":"traceutil/trace.go:171","msg":"trace[1724503829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"506.571152ms","start":"2026-04-13T06:09:12.605861Z","end":"2026-04-13T06:09:13.112432Z","steps":["trace[1724503829] 'agreement among raft nodes before linearized reading' (duration: 506.48237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.112533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.270777829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T06:09:13.112574Z","caller":"traceutil/trace.go:171","msg":"trace[460962572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"2.270878192s","start":"2026-04-13T06:09:10.841684Z","end":"2026-04-13T06:09:13.112562Z","steps":["trace[460962572] 'agreement among raft nodes before linearized reading' (duration: 2.27080845s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.112571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:12.60584Z","time spent":"506.714667ms","remote":"127.0.0.1:41222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:13.1123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318725182s","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-04-13T06:09:13.112808Z","caller":"traceutil/trace.go:171","msg":"trace[1681511961] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"2.319311399s","start":"2026-04-13T06:09:10.79348Z","end":"2026-04-13T06:09:13.112792Z","steps":["trace[1681511961] 'agreement among raft nodes before linearized reading' (duration: 2.318710022s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.112859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:10.79345Z","time spent":"2.31939593s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T06:09:13.112162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.657514047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"warn","ts":"2026-04-13T06:09:13.11263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:10.841653Z","time spent":"2.270962644s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-13T06:09:13.112985Z","caller":"traceutil/trace.go:171","msg":"trace[1278615334] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:414; }","duration":"3.658312629s","start":"2026-04-13T06:09:09.45458Z","end":"2026-04-13T06:09:13.112892Z","steps":["trace[1278615334] 'agreement among raft nodes before linearized reading' (duration: 3.657345023s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.113054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:09.454557Z","time spent":"3.658479343s","remote":"127.0.0.1:41380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-13T06:09:13.70327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.233086ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-13T06:09:13.703376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.113517Z","time spent":"589.854813ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T06:09:13.791916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.118555Z","time spent":"673.347077ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/13 06:09:13 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-13T06:09:13.792266Z","caller":"traceutil/trace.go:171","msg":"trace[513223613] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:428; }","duration":"668.956266ms","start":"2026-04-13T06:09:13.12329Z","end":"2026-04-13T06:09:13.792246Z","steps":["trace[513223613] 'read index received' (duration: 312.726033ms)","trace[513223613] 'applied index is now lower than readState.Index' (duration: 356.229433ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:09:13.79238Z","caller":"traceutil/trace.go:171","msg":"trace[1218842643] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"668.953206ms","start":"2026-04-13T06:09:13.123416Z","end":"2026-04-13T06:09:13.792369Z","steps":["trace[1218842643] 'process raft request' (duration: 668.787831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.792476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.123385Z","time spent":"669.019307ms","remote":"127.0.0.1:41380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4115,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:09:13.792454Z","caller":"traceutil/trace.go:171","msg":"trace[2084348062] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"675.159307ms","start":"2026-04-13T06:09:13.117247Z","end":"2026-04-13T06:09:13.792407Z","steps":["trace[2084348062] 'process raft request' (duration: 674.858839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.79259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.299845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:09:13.79262Z","caller":"traceutil/trace.go:171","msg":"trace[92780353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"669.347266ms","start":"2026-04-13T06:09:13.123261Z","end":"2026-04-13T06:09:13.792609Z","steps":["trace[92780353] 'agreement among raft nodes before linearized reading' (duration: 669.301285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.792647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.123252Z","time spent":"669.389747ms","remote":"127.0.0.1:41218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T06:09:13.792772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.117232Z","time spent":"675.377462ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:09:13.793017Z","caller":"traceutil/trace.go:171","msg":"trace[1803310701] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"676.173314ms","start":"2026-04-13T06:09:13.116061Z","end":"2026-04-13T06:09:13.792234Z","steps":["trace[1803310701] 'process raft request' (duration: 675.927837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:13.793282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.116038Z","time spent":"677.141921ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:09:14.204815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.863871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T06:09:14.205012Z","caller":"traceutil/trace.go:171","msg":"trace[356157025] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"406.958957ms","start":"2026-04-13T06:09:13.798035Z","end":"2026-04-13T06:09:14.204994Z","steps":["trace[356157025] 'process raft request' (duration: 406.899545ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:14.205Z","caller":"traceutil/trace.go:171","msg":"trace[342260713] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"500.17771ms","start":"2026-04-13T06:09:13.704779Z","end":"2026-04-13T06:09:14.204957Z","steps":["trace[342260713] 'process raft request' (duration: 158.071092ms)","trace[342260713] 'compare' (duration: 341.720517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:09:14.205102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.798019Z","time spent":"407.039111ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T06:09:14.205061Z","caller":"traceutil/trace.go:171","msg":"trace[48818298] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"412.685126ms","start":"2026-04-13T06:09:13.792337Z","end":"2026-04-13T06:09:14.205022Z","steps":["trace[48818298] 'read index received' (duration: 70.519366ms)","trace[48818298] 'applied index is now lower than readState.Index' (duration: 342.16172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:09:14.205275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.704758Z","time spent":"500.340485ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:09:14.205434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.75181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:09:14.205522Z","caller":"traceutil/trace.go:171","msg":"trace[113517367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"411.886863ms","start":"2026-04-13T06:09:13.793618Z","end":"2026-04-13T06:09:14.205505Z","steps":["trace[113517367] 'agreement among raft nodes before linearized reading' (duration: 411.652777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:14.205544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.562292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7102"} {"level":"warn","ts":"2026-04-13T06:09:14.205607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.793606Z","time spent":"411.963206ms","remote":"127.0.0.1:41222","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-04-13T06:09:14.205647Z","caller":"traceutil/trace.go:171","msg":"trace[821679824] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:420; }","duration":"408.722416ms","start":"2026-04-13T06:09:13.796906Z","end":"2026-04-13T06:09:14.205628Z","steps":["trace[821679824] 'agreement among raft nodes before linearized reading' (duration: 408.47586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:14.205702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.796885Z","time spent":"408.807578ms","remote":"127.0.0.1:41380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7125,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-04-13T06:09:14.20592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.605359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-04-13T06:09:14.20599Z","caller":"traceutil/trace.go:171","msg":"trace[35177917] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:420; }","duration":"877.718663ms","start":"2026-04-13T06:09:13.328254Z","end":"2026-04-13T06:09:14.205973Z","steps":["trace[35177917] 'agreement among raft nodes before linearized reading' (duration: 876.901821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:14.206046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:13.328233Z","time spent":"877.799145ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12696,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-13T06:09:37.446335Z","caller":"traceutil/trace.go:171","msg":"trace[254962649] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"262.874426ms","start":"2026-04-13T06:09:37.183438Z","end":"2026-04-13T06:09:37.446313Z","steps":["trace[254962649] 'process raft request' (duration: 262.835414ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:37.446459Z","caller":"traceutil/trace.go:171","msg":"trace[873227841] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"263.613476ms","start":"2026-04-13T06:09:37.182804Z","end":"2026-04-13T06:09:37.446417Z","steps":["trace[873227841] 'process raft request' (duration: 263.328578ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:43.745421Z","caller":"traceutil/trace.go:171","msg":"trace[1879279563] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"158.689322ms","start":"2026-04-13T06:09:43.586697Z","end":"2026-04-13T06:09:43.745386Z","steps":["trace[1879279563] 'read index received' (duration: 158.556958ms)","trace[1879279563] 'applied index is now lower than readState.Index' (duration: 130.544µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:09:43.745518Z","caller":"traceutil/trace.go:171","msg":"trace[2035243737] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"267.310658ms","start":"2026-04-13T06:09:43.478179Z","end":"2026-04-13T06:09:43.745489Z","steps":["trace[2035243737] 'process raft request' (duration: 267.162324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:43.745592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.858016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:09:43.745475Z","caller":"traceutil/trace.go:171","msg":"trace[1430777162] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"267.243257ms","start":"2026-04-13T06:09:43.478178Z","end":"2026-04-13T06:09:43.745421Z","steps":["trace[1430777162] 'process raft request' (duration: 266.966919ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:43.74567Z","caller":"traceutil/trace.go:171","msg":"trace[2074702838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"158.971299ms","start":"2026-04-13T06:09:43.586648Z","end":"2026-04-13T06:09:43.74562Z","steps":["trace[2074702838] 'agreement among raft nodes before linearized reading' (duration: 158.862926ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:43.891988Z","caller":"traceutil/trace.go:171","msg":"trace[384118801] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"237.208176ms","start":"2026-04-13T06:09:43.654754Z","end":"2026-04-13T06:09:43.891962Z","steps":["trace[384118801] 'process raft request' (duration: 237.157174ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:43.892092Z","caller":"traceutil/trace.go:171","msg":"trace[563765236] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"285.150262ms","start":"2026-04-13T06:09:43.606907Z","end":"2026-04-13T06:09:43.892057Z","steps":["trace[563765236] 'process raft request' (duration: 284.805942ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:09:58.501315Z","caller":"traceutil/trace.go:171","msg":"trace[1403113625] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"221.293625ms","start":"2026-04-13T06:09:58.27998Z","end":"2026-04-13T06:09:58.501274Z","steps":["trace[1403113625] 'read index received' (duration: 217.197502ms)","trace[1403113625] 'applied index is now lower than readState.Index' (duration: 4.094313ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:09:58.501308Z","caller":"traceutil/trace.go:171","msg":"trace[1872131138] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"373.656162ms","start":"2026-04-13T06:09:58.127623Z","end":"2026-04-13T06:09:58.501279Z","steps":["trace[1872131138] 'process raft request' (duration: 369.659811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:09:58.501589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:09:58.127601Z","time spent":"373.856207ms","remote":"127.0.0.1:41380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:09:58.502087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.078007ms","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-04-13T06:09:58.50219Z","caller":"traceutil/trace.go:171","msg":"trace[87114044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"222.242182ms","start":"2026-04-13T06:09:58.279925Z","end":"2026-04-13T06:09:58.502167Z","steps":["trace[87114044] 'agreement among raft nodes before linearized reading' (duration: 221.974774ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:10:41.460511Z","caller":"traceutil/trace.go:171","msg":"trace[1930544958] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"110.177992ms","start":"2026-04-13T06:10:41.350303Z","end":"2026-04-13T06:10:41.460481Z","steps":["trace[1930544958] 'process raft request' (duration: 108.713371ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:10:47.542767Z","caller":"traceutil/trace.go:171","msg":"trace[552209565] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"145.087096ms","start":"2026-04-13T06:10:47.397636Z","end":"2026-04-13T06:10:47.542723Z","steps":["trace[552209565] 'process raft request' (duration: 140.821059ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:10:47.659565Z","caller":"traceutil/trace.go:171","msg":"trace[1349882812] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"111.830327ms","start":"2026-04-13T06:10:47.547701Z","end":"2026-04-13T06:10:47.659531Z","steps":["trace[1349882812] 'process raft request' (duration: 99.650921ms)","trace[1349882812] 'compare' (duration: 12.025822ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:10:47.664021Z","caller":"traceutil/trace.go:171","msg":"trace[722181522] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"116.099085ms","start":"2026-04-13T06:10:47.547838Z","end":"2026-04-13T06:10:47.663937Z","steps":["trace[722181522] 'process raft request' (duration: 115.776016ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:10:54.013167Z","caller":"traceutil/trace.go:171","msg":"trace[1584502872] linearizableReadLoop","detail":"{readStateIndex:1584; appliedIndex:1583; }","duration":"316.023064ms","start":"2026-04-13T06:10:53.697118Z","end":"2026-04-13T06:10:54.013141Z","steps":["trace[1584502872] 'read index received' (duration: 315.8608ms)","trace[1584502872] 'applied index is now lower than readState.Index' (duration: 155.694µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:10:54.013223Z","caller":"traceutil/trace.go:171","msg":"trace[1603886948] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"321.594419ms","start":"2026-04-13T06:10:53.691596Z","end":"2026-04-13T06:10:54.01319Z","steps":["trace[1603886948] 'process raft request' (duration: 321.370913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:10:54.013338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.20117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:10:54.013376Z","caller":"traceutil/trace.go:171","msg":"trace[685362814] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:0; response_revision:1538; }","duration":"316.260662ms","start":"2026-04-13T06:10:53.697105Z","end":"2026-04-13T06:10:54.013366Z","steps":["trace[685362814] 'agreement among raft nodes before linearized reading' (duration: 316.17408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:10:54.013393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:10:53.691579Z","time spent":"321.719072ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:10:54.013419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:10:53.697057Z","time spent":"316.353325ms","remote":"127.0.0.1:41292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-04-13T06:10:54.013445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.025502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:10:54.013519Z","caller":"traceutil/trace.go:171","msg":"trace[355010584] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1538; }","duration":"221.111035ms","start":"2026-04-13T06:10:53.792388Z","end":"2026-04-13T06:10:54.013499Z","steps":["trace[355010584] 'agreement among raft nodes before linearized reading' (duration: 220.984991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:10:54.01356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.240036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:10:54.013646Z","caller":"traceutil/trace.go:171","msg":"trace[124382601] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1538; }","duration":"180.36121ms","start":"2026-04-13T06:10:53.833265Z","end":"2026-04-13T06:10:54.013626Z","steps":["trace[124382601] 'agreement among raft nodes before linearized reading' (duration: 180.225877ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:13.065885Z","caller":"traceutil/trace.go:171","msg":"trace[681324531] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"144.52455ms","start":"2026-04-13T06:11:12.921326Z","end":"2026-04-13T06:11:13.06585Z","steps":["trace[681324531] 'process raft request' (duration: 144.250833ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:13.226711Z","caller":"traceutil/trace.go:171","msg":"trace[814267636] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"161.703384ms","start":"2026-04-13T06:11:13.06495Z","end":"2026-04-13T06:11:13.226654Z","steps":["trace[814267636] 'process raft request' (duration: 110.923752ms)","trace[814267636] 'compare' (duration: 50.409312ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:11:13.677731Z","caller":"traceutil/trace.go:171","msg":"trace[732907055] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"196.795824ms","start":"2026-04-13T06:11:13.480899Z","end":"2026-04-13T06:11:13.677695Z","steps":["trace[732907055] 'process raft request' (duration: 196.595509ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:27.77972Z","caller":"traceutil/trace.go:171","msg":"trace[1168276792] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"151.374672ms","start":"2026-04-13T06:11:27.62831Z","end":"2026-04-13T06:11:27.779684Z","steps":["trace[1168276792] 'process raft request' (duration: 151.143975ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:58.431843Z","caller":"traceutil/trace.go:171","msg":"trace[66683787] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"112.910547ms","start":"2026-04-13T06:11:58.31888Z","end":"2026-04-13T06:11:58.43179Z","steps":["trace[66683787] 'process raft request' (duration: 112.705592ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:58.601842Z","caller":"traceutil/trace.go:171","msg":"trace[1728699171] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"158.507908ms","start":"2026-04-13T06:11:58.44329Z","end":"2026-04-13T06:11:58.601798Z","steps":["trace[1728699171] 'read index received' (duration: 126.719666ms)","trace[1728699171] 'applied index is now lower than readState.Index' (duration: 31.786832ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:11:58.601926Z","caller":"traceutil/trace.go:171","msg":"trace[308587828] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"176.476641ms","start":"2026-04-13T06:11:58.425391Z","end":"2026-04-13T06:11:58.601868Z","steps":["trace[308587828] 'process raft request' (duration: 144.7096ms)","trace[308587828] 'compare' (duration: 31.470133ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:11:58.60222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.898149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T06:11:58.602289Z","caller":"traceutil/trace.go:171","msg":"trace[2002355675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2157; }","duration":"159.022083ms","start":"2026-04-13T06:11:58.443244Z","end":"2026-04-13T06:11:58.602266Z","steps":["trace[2002355675] 'agreement among raft nodes before linearized reading' (duration: 158.789695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:11:58.602463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.037934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T06:11:58.602424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.967373ms","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-04-13T06:11:58.602533Z","caller":"traceutil/trace.go:171","msg":"trace[435866523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2157; }","duration":"100.143727ms","start":"2026-04-13T06:11:58.502372Z","end":"2026-04-13T06:11:58.602516Z","steps":["trace[435866523] 'agreement among raft nodes before linearized reading' (duration: 99.866449ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:58.602561Z","caller":"traceutil/trace.go:171","msg":"trace[565819533] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2157; }","duration":"119.206019ms","start":"2026-04-13T06:11:58.483322Z","end":"2026-04-13T06:11:58.602528Z","steps":["trace[565819533] 'agreement among raft nodes before linearized reading' (duration: 118.761917ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:58.750272Z","caller":"traceutil/trace.go:171","msg":"trace[426820146] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"142.580651ms","start":"2026-04-13T06:11:58.607664Z","end":"2026-04-13T06:11:58.750244Z","steps":["trace[426820146] 'process raft request' (duration: 142.506049ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:11:58.750332Z","caller":"traceutil/trace.go:171","msg":"trace[1572613872] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"142.665783ms","start":"2026-04-13T06:11:58.607629Z","end":"2026-04-13T06:11:58.750295Z","steps":["trace[1572613872] 'process raft request' (duration: 104.316021ms)","trace[1572613872] 'compare' (duration: 38.085275ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:11:59.102901Z","caller":"traceutil/trace.go:171","msg":"trace[1044979835] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"172.769659ms","start":"2026-04-13T06:11:58.930102Z","end":"2026-04-13T06:11:59.102872Z","steps":["trace[1044979835] 'process raft request' (duration: 131.22862ms)","trace[1044979835] 'compare' (duration: 41.008615ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:12:04.882532Z","caller":"traceutil/trace.go:171","msg":"trace[74613214] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"111.350934ms","start":"2026-04-13T06:12:04.771151Z","end":"2026-04-13T06:12:04.882502Z","steps":["trace[74613214] 'read index received' (duration: 50.781742ms)","trace[74613214] 'applied index is now lower than readState.Index' (duration: 60.568162ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:12:04.882688Z","caller":"traceutil/trace.go:171","msg":"trace[188724212] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"154.307733ms","start":"2026-04-13T06:12:04.728337Z","end":"2026-04-13T06:12:04.882645Z","steps":["trace[188724212] 'process raft request' (duration: 93.576507ms)","trace[188724212] 'compare' (duration: 60.459008ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:12:04.882892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.723866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-13T06:12:04.882911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.682701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T06:12:04.883046Z","caller":"traceutil/trace.go:171","msg":"trace[1872955522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2188; }","duration":"108.885718ms","start":"2026-04-13T06:12:04.774143Z","end":"2026-04-13T06:12:04.883029Z","steps":["trace[1872955522] 'agreement among raft nodes before linearized reading' (duration: 108.597749ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:04.882984Z","caller":"traceutil/trace.go:171","msg":"trace[571074163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2188; }","duration":"111.88148ms","start":"2026-04-13T06:12:04.771085Z","end":"2026-04-13T06:12:04.882966Z","steps":["trace[571074163] 'agreement among raft nodes before linearized reading' (duration: 111.612362ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:05.062526Z","caller":"traceutil/trace.go:171","msg":"trace[265260577] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"119.744505ms","start":"2026-04-13T06:12:04.942738Z","end":"2026-04-13T06:12:05.062482Z","steps":["trace[265260577] 'process raft request' (duration: 107.378165ms)","trace[265260577] 'compare' (duration: 12.227746ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:12:05.318116Z","caller":"traceutil/trace.go:171","msg":"trace[529677498] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"189.553959ms","start":"2026-04-13T06:12:05.12851Z","end":"2026-04-13T06:12:05.318064Z","steps":["trace[529677498] 'process raft request' (duration: 173.53601ms)","trace[529677498] 'compare' (duration: 15.760153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:12:05.681353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.793518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T06:12:05.681606Z","caller":"traceutil/trace.go:171","msg":"trace[1380358236] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"299.66464ms","start":"2026-04-13T06:12:05.381896Z","end":"2026-04-13T06:12:05.681561Z","steps":["trace[1380358236] 'process raft request' (duration: 106.559193ms)","trace[1380358236] 'compare' (duration: 192.644135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:12:11.121263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.581268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T06:12:11.121384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.605481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-13T06:12:11.121473Z","caller":"traceutil/trace.go:171","msg":"trace[509437501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2219; }","duration":"154.732464ms","start":"2026-04-13T06:12:10.966723Z","end":"2026-04-13T06:12:11.121455Z","steps":["trace[509437501] 'range keys from in-memory index tree' (duration: 154.292252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:11.121289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.610764ms","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-04-13T06:12:11.121615Z","caller":"traceutil/trace.go:171","msg":"trace[1588596440] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2219; }","duration":"303.820334ms","start":"2026-04-13T06:12:10.817602Z","end":"2026-04-13T06:12:11.121423Z","steps":["trace[1588596440] 'count revisions from in-memory index tree' (duration: 303.443264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:11.121749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:10.81758Z","time spent":"304.143113ms","remote":"127.0.0.1:41656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":2,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-13T06:12:11.12162Z","caller":"traceutil/trace.go:171","msg":"trace[359339389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2219; }","duration":"215.929503ms","start":"2026-04-13T06:12:10.905657Z","end":"2026-04-13T06:12:11.121586Z","steps":["trace[359339389] 'range keys from in-memory index tree' (duration: 215.43486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:11.121318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.897723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T06:12:11.122748Z","caller":"traceutil/trace.go:171","msg":"trace[1822444428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2219; }","duration":"135.362843ms","start":"2026-04-13T06:12:10.987363Z","end":"2026-04-13T06:12:11.122726Z","steps":["trace[1822444428] 'range keys from in-memory index tree' (duration: 133.697168ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:12:28.827914Z","caller":"traceutil/trace.go:171","msg":"trace[1226474626] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"366.831462ms","start":"2026-04-13T06:12:28.461062Z","end":"2026-04-13T06:12:28.827893Z","steps":["trace[1226474626] 'process raft request' (duration: 366.592236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:12:28.828229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:12:28.46103Z","time spent":"366.964456ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:13:29.585459Z","caller":"traceutil/trace.go:171","msg":"trace[590951336] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"167.646788ms","start":"2026-04-13T06:13:29.417778Z","end":"2026-04-13T06:13:29.585425Z","steps":["trace[590951336] 'process raft request' (duration: 167.492784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:29.92328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.304467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-13T06:13:29.923384Z","caller":"traceutil/trace.go:171","msg":"trace[1754359062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2842; }","duration":"160.457852ms","start":"2026-04-13T06:13:29.762897Z","end":"2026-04-13T06:13:29.923355Z","steps":["trace[1754359062] 'range keys from in-memory index tree' (duration: 160.079371ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:30.16383Z","caller":"traceutil/trace.go:171","msg":"trace[2072964900] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"235.369337ms","start":"2026-04-13T06:13:29.928401Z","end":"2026-04-13T06:13:30.16377Z","steps":["trace[2072964900] 'process raft request' (duration: 235.160751ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:38.100041Z","caller":"traceutil/trace.go:171","msg":"trace[493792475] transaction","detail":"{read_only:false; response_revision:2882; number_of_response:1; }","duration":"445.487244ms","start":"2026-04-13T06:13:37.654523Z","end":"2026-04-13T06:13:38.10001Z","steps":["trace[493792475] 'process raft request' (duration: 445.179426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.100227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.654493Z","time spent":"445.643108ms","remote":"127.0.0.1:41276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:13:38.880041Z","caller":"traceutil/trace.go:171","msg":"trace[850664136] linearizableReadLoop","detail":"{readStateIndex:2990; appliedIndex:2988; }","duration":"1.211784911s","start":"2026-04-13T06:13:37.668233Z","end":"2026-04-13T06:13:38.880018Z","steps":["trace[850664136] 'read index received' (duration: 431.468833ms)","trace[850664136] 'applied index is now lower than readState.Index' (duration: 780.314828ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:13:38.880225Z","caller":"traceutil/trace.go:171","msg":"trace[1487918693] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"1.221028933s","start":"2026-04-13T06:13:37.65913Z","end":"2026-04-13T06:13:38.880159Z","steps":["trace[1487918693] 'process raft request' (duration: 1.220142118s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.880356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2121064s","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-04-13T06:13:38.880419Z","caller":"traceutil/trace.go:171","msg":"trace[772535203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2883; }","duration":"1.212218782s","start":"2026-04-13T06:13:37.668181Z","end":"2026-04-13T06:13:38.8804Z","steps":["trace[772535203] 'agreement among raft nodes before linearized reading' (duration: 1.211978286s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.880461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.668168Z","time spent":"1.212284744s","remote":"127.0.0.1:41466","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-04-13T06:13:38.880469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.659112Z","time spent":"1.221245528s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:13:38.882232Z","caller":"traceutil/trace.go:171","msg":"trace[550075232] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"577.908337ms","start":"2026-04-13T06:13:38.304288Z","end":"2026-04-13T06:13:38.882196Z","steps":["trace[550075232] 'process raft request' (duration: 577.66322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.882364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.304263Z","time spent":"578.03856ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:13:38.882588Z","caller":"traceutil/trace.go:171","msg":"trace[762371347] transaction","detail":"{read_only:false; response_revision:2885; number_of_response:1; }","duration":"544.680481ms","start":"2026-04-13T06:13:38.337874Z","end":"2026-04-13T06:13:38.882554Z","steps":["trace[762371347] 'process raft request' (duration: 544.217569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.882783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.337849Z","time spent":"544.825715ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:13:38.88295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207112263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-13T06:13:38.882954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.600569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:13:38.883028Z","caller":"traceutil/trace.go:171","msg":"trace[509851452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2886; }","duration":"1.207231237s","start":"2026-04-13T06:13:37.675779Z","end":"2026-04-13T06:13:38.88301Z","steps":["trace[509851452] 'agreement among raft nodes before linearized reading' (duration: 1.207033812s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.883082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.675771Z","time spent":"1.207298919s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T06:13:38.883213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.653981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-13T06:13:38.883256Z","caller":"traceutil/trace.go:171","msg":"trace[399750524] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2886; }","duration":"529.730283ms","start":"2026-04-13T06:13:38.353516Z","end":"2026-04-13T06:13:38.883247Z","steps":["trace[399750524] 'agreement among raft nodes before linearized reading' (duration: 529.567289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.883282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.353504Z","time spent":"529.771525ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-04-13T06:13:38.883283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.5379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:13:38.883324Z","caller":"traceutil/trace.go:171","msg":"trace[849425942] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:2886; }","duration":"981.607952ms","start":"2026-04-13T06:13:37.901707Z","end":"2026-04-13T06:13:38.883315Z","steps":["trace[849425942] 'agreement among raft nodes before linearized reading' (duration: 981.535959ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:38.883316Z","caller":"traceutil/trace.go:171","msg":"trace[806721864] transaction","detail":"{read_only:false; response_revision:2886; number_of_response:1; }","duration":"273.907583ms","start":"2026-04-13T06:13:38.609386Z","end":"2026-04-13T06:13:38.883293Z","steps":["trace[806721864] 'process raft request' (duration: 273.320877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.883359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.901695Z","time spent":"981.656972ms","remote":"127.0.0.1:41668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:13:38.883398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207506275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T06:13:38.883439Z","caller":"traceutil/trace.go:171","msg":"trace[1938782152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2886; }","duration":"1.207589617s","start":"2026-04-13T06:13:37.675839Z","end":"2026-04-13T06:13:38.883428Z","steps":["trace[1938782152] 'agreement among raft nodes before linearized reading' (duration: 1.207485254s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.883465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.67582Z","time spent":"1.207639317s","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T06:13:38.883036Z","caller":"traceutil/trace.go:171","msg":"trace[1373864335] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2886; }","duration":"665.725772ms","start":"2026-04-13T06:13:38.217294Z","end":"2026-04-13T06:13:38.883019Z","steps":["trace[1373864335] 'agreement among raft nodes before linearized reading' (duration: 665.572788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.883544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.217288Z","time spent":"666.244996ms","remote":"127.0.0.1:43056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:13:38.883557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038074932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:13:38.88359Z","caller":"traceutil/trace.go:171","msg":"trace[1395481079] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2886; }","duration":"1.038149742s","start":"2026-04-13T06:13:37.845433Z","end":"2026-04-13T06:13:38.883583Z","steps":["trace[1395481079] 'agreement among raft nodes before linearized reading' (duration: 1.038070291s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.883619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:37.845416Z","time spent":"1.038196836s","remote":"127.0.0.1:41306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":10,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-13T06:13:38.883651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.404301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T06:13:38.883651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.690088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T06:13:38.883691Z","caller":"traceutil/trace.go:171","msg":"trace[1019699792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2886; }","duration":"666.491283ms","start":"2026-04-13T06:13:38.217189Z","end":"2026-04-13T06:13:38.88368Z","steps":["trace[1019699792] 'agreement among raft nodes before linearized reading' (duration: 666.38587ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:38.883696Z","caller":"traceutil/trace.go:171","msg":"trace[1646740862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2886; }","duration":"681.7931ms","start":"2026-04-13T06:13:38.201892Z","end":"2026-04-13T06:13:38.883685Z","steps":["trace[1646740862] 'agreement among raft nodes before linearized reading' (duration: 681.571744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.88373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.201865Z","time spent":"681.856524ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T06:13:38.883732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.217166Z","time spent":"666.555706ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T06:13:38.883879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.866292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-13T06:13:38.883914Z","caller":"traceutil/trace.go:171","msg":"trace[2143983393] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2886; }","duration":"641.944933ms","start":"2026-04-13T06:13:38.241962Z","end":"2026-04-13T06:13:38.883907Z","steps":["trace[2143983393] 'agreement among raft nodes before linearized reading' (duration: 641.198103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:13:38.883997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:13:38.241942Z","time spent":"642.044348ms","remote":"127.0.0.1:41380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-13T06:13:39.484584Z","caller":"traceutil/trace.go:171","msg":"trace[2127418763] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"298.288508ms","start":"2026-04-13T06:13:39.18627Z","end":"2026-04-13T06:13:39.484559Z","steps":["trace[2127418763] 'process raft request' (duration: 298.075972ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:13:39.485638Z","caller":"traceutil/trace.go:171","msg":"trace[104969993] transaction","detail":"{read_only:false; response_revision:2893; number_of_response:1; }","duration":"173.854553ms","start":"2026-04-13T06:13:39.311753Z","end":"2026-04-13T06:13:39.485608Z","steps":["trace[104969993] 'process raft request' (duration: 173.679639ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:16:01.387113Z","caller":"traceutil/trace.go:171","msg":"trace[1193725788] transaction","detail":"{read_only:false; response_revision:3832; number_of_response:1; }","duration":"163.567591ms","start":"2026-04-13T06:16:01.223518Z","end":"2026-04-13T06:16:01.387086Z","steps":["trace[1193725788] 'process raft request' (duration: 163.012195ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:16:06.671824Z","caller":"traceutil/trace.go:171","msg":"trace[1680338435] transaction","detail":"{read_only:false; response_revision:3863; number_of_response:1; }","duration":"114.647242ms","start":"2026-04-13T06:16:06.557125Z","end":"2026-04-13T06:16:06.671773Z","steps":["trace[1680338435] 'process raft request' (duration: 114.010574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:28.162331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.083847ms","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-04-13T06:17:28.16248Z","caller":"traceutil/trace.go:171","msg":"trace[393301605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5046; }","duration":"101.291252ms","start":"2026-04-13T06:17:28.061161Z","end":"2026-04-13T06:17:28.162452Z","steps":["trace[393301605] 'range keys from in-memory index tree' (duration: 100.933123ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:28.369351Z","caller":"traceutil/trace.go:171","msg":"trace[1943929857] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"202.481922ms","start":"2026-04-13T06:17:28.16684Z","end":"2026-04-13T06:17:28.369322Z","steps":["trace[1943929857] 'process raft request' (duration: 202.312608ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:28.408166Z","caller":"traceutil/trace.go:171","msg":"trace[1268835510] linearizableReadLoop","detail":"{readStateIndex:5239; appliedIndex:5234; }","duration":"240.508771ms","start":"2026-04-13T06:17:28.167611Z","end":"2026-04-13T06:17:28.408119Z","steps":["trace[1268835510] 'read index received' (duration: 201.568197ms)","trace[1268835510] 'applied index is now lower than readState.Index' (duration: 38.938694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:17:28.408581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.931782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16844"} {"level":"info","ts":"2026-04-13T06:17:28.40864Z","caller":"traceutil/trace.go:171","msg":"trace[181218006] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5052; }","duration":"241.060375ms","start":"2026-04-13T06:17:28.167561Z","end":"2026-04-13T06:17:28.408621Z","steps":["trace[181218006] 'agreement among raft nodes before linearized reading' (duration: 240.725686ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:28.409199Z","caller":"traceutil/trace.go:171","msg":"trace[631856846] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"241.745084ms","start":"2026-04-13T06:17:28.167437Z","end":"2026-04-13T06:17:28.409182Z","steps":["trace[631856846] 'process raft request' (duration: 240.166981ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:28.409395Z","caller":"traceutil/trace.go:171","msg":"trace[1271533380] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"241.928089ms","start":"2026-04-13T06:17:28.167454Z","end":"2026-04-13T06:17:28.409382Z","steps":["trace[1271533380] 'process raft request' (duration: 240.410398ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:28.409552Z","caller":"traceutil/trace.go:171","msg":"trace[1418785186] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"242.056943ms","start":"2026-04-13T06:17:28.167483Z","end":"2026-04-13T06:17:28.40954Z","steps":["trace[1418785186] 'process raft request' (duration: 240.452079ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:28.409761Z","caller":"traceutil/trace.go:171","msg":"trace[1028528934] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"242.220468ms","start":"2026-04-13T06:17:28.167526Z","end":"2026-04-13T06:17:28.409747Z","steps":["trace[1028528934] 'process raft request' (duration: 240.464549ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:38.579835Z","caller":"traceutil/trace.go:171","msg":"trace[1689595352] transaction","detail":"{read_only:false; response_revision:5245; number_of_response:1; }","duration":"143.091474ms","start":"2026-04-13T06:17:38.436691Z","end":"2026-04-13T06:17:38.579782Z","steps":["trace[1689595352] 'process raft request' (duration: 142.92474ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:38.592078Z","caller":"traceutil/trace.go:171","msg":"trace[1279852991] linearizableReadLoop","detail":"{readStateIndex:5440; appliedIndex:5438; }","duration":"117.18419ms","start":"2026-04-13T06:17:38.474854Z","end":"2026-04-13T06:17:38.592039Z","steps":["trace[1279852991] 'read index received' (duration: 104.804028ms)","trace[1279852991] 'applied index is now lower than readState.Index' (duration: 12.379002ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:17:38.592112Z","caller":"traceutil/trace.go:171","msg":"trace[382636093] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"118.586479ms","start":"2026-04-13T06:17:38.473495Z","end":"2026-04-13T06:17:38.592081Z","steps":["trace[382636093] 'process raft request' (duration: 118.405813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:38.592332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.094516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-13T06:17:38.592355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.478839ms","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-04-13T06:17:38.592387Z","caller":"traceutil/trace.go:171","msg":"trace[1238590908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5246; }","duration":"114.198218ms","start":"2026-04-13T06:17:38.478173Z","end":"2026-04-13T06:17:38.592372Z","steps":["trace[1238590908] 'agreement among raft nodes before linearized reading' (duration: 114.054374ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:38.59239Z","caller":"traceutil/trace.go:171","msg":"trace[1266350985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5246; }","duration":"117.57467ms","start":"2026-04-13T06:17:38.474805Z","end":"2026-04-13T06:17:38.59238Z","steps":["trace[1266350985] 'agreement among raft nodes before linearized reading' (duration: 117.392196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:38.592577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.329312ms","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-04-13T06:17:38.592614Z","caller":"traceutil/trace.go:171","msg":"trace[1312012623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5246; }","duration":"114.374964ms","start":"2026-04-13T06:17:38.478231Z","end":"2026-04-13T06:17:38.592606Z","steps":["trace[1312012623] 'agreement among raft nodes before linearized reading' (duration: 114.280331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:38.592756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.152368ms","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-04-13T06:17:38.592903Z","caller":"traceutil/trace.go:171","msg":"trace[171528366] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5246; }","duration":"109.349384ms","start":"2026-04-13T06:17:38.483523Z","end":"2026-04-13T06:17:38.592872Z","steps":["trace[171528366] 'agreement among raft nodes before linearized reading' (duration: 108.958713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:47.344021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.119304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-13T06:17:47.344255Z","caller":"traceutil/trace.go:171","msg":"trace[1326672585] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:5424; }","duration":"135.429184ms","start":"2026-04-13T06:17:47.208804Z","end":"2026-04-13T06:17:47.344233Z","steps":["trace[1326672585] 'range keys from in-memory index tree' (duration: 134.830487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:47.708851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.373371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:17:47.708935Z","caller":"traceutil/trace.go:171","msg":"trace[154977532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5428; }","duration":"123.505205ms","start":"2026-04-13T06:17:47.585408Z","end":"2026-04-13T06:17:47.708913Z","steps":["trace[154977532] 'range keys from in-memory index tree' (duration: 123.283918ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:48.04025Z","caller":"traceutil/trace.go:171","msg":"trace[10363770] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"143.570388ms","start":"2026-04-13T06:17:47.896658Z","end":"2026-04-13T06:17:48.040228Z","steps":["trace[10363770] 'process raft request' (duration: 143.447324ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:48.100571Z","caller":"traceutil/trace.go:171","msg":"trace[610710800] linearizableReadLoop","detail":"{readStateIndex:5628; appliedIndex:5626; }","duration":"106.324611ms","start":"2026-04-13T06:17:47.994231Z","end":"2026-04-13T06:17:48.100556Z","steps":["trace[610710800] 'read index received' (duration: 46.07864ms)","trace[610710800] 'applied index is now lower than readState.Index' (duration: 60.244081ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:17:48.100654Z","caller":"traceutil/trace.go:171","msg":"trace[331033809] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"199.818448ms","start":"2026-04-13T06:17:47.900777Z","end":"2026-04-13T06:17:48.100596Z","steps":["trace[331033809] 'process raft request' (duration: 199.609312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:48.100803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.550478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-13T06:17:48.100837Z","caller":"traceutil/trace.go:171","msg":"trace[1828208401] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5432; }","duration":"106.66128ms","start":"2026-04-13T06:17:47.994166Z","end":"2026-04-13T06:17:48.100828Z","steps":["trace[1828208401] 'agreement among raft nodes before linearized reading' (duration: 106.473715ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:48.100506Z","caller":"traceutil/trace.go:171","msg":"trace[2001468494] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"200.506788ms","start":"2026-04-13T06:17:47.899971Z","end":"2026-04-13T06:17:48.100478Z","steps":["trace[2001468494] 'process raft request' (duration: 200.25133ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:17:48.231871Z","caller":"traceutil/trace.go:171","msg":"trace[402408781] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"125.8764ms","start":"2026-04-13T06:17:48.105967Z","end":"2026-04-13T06:17:48.231844Z","steps":["trace[402408781] 'process raft request' (duration: 38.073908ms)","trace[402408781] 'compare' (duration: 87.240246ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:17:49.9769Z","caller":"traceutil/trace.go:171","msg":"trace[1386704989] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"201.360393ms","start":"2026-04-13T06:17:49.775501Z","end":"2026-04-13T06:17:49.976862Z","steps":["trace[1386704989] 'process raft request' (duration: 148.191806ms)","trace[1386704989] 'compare' (duration: 52.679563ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:17:49.976883Z","caller":"traceutil/trace.go:171","msg":"trace[165781901] linearizableReadLoop","detail":"{readStateIndex:5642; appliedIndex:5641; }","duration":"182.413321ms","start":"2026-04-13T06:17:49.794439Z","end":"2026-04-13T06:17:49.976853Z","steps":["trace[165781901] 'read index received' (duration: 129.188533ms)","trace[165781901] 'applied index is now lower than readState.Index' (duration: 53.221928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:17:49.977407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.056577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45ntx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-13T06:17:49.97749Z","caller":"traceutil/trace.go:171","msg":"trace[120473522] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45ntx; range_end:; response_count:1; response_revision:5446; }","duration":"126.207401ms","start":"2026-04-13T06:17:49.851264Z","end":"2026-04-13T06:17:49.977471Z","steps":["trace[120473522] 'agreement among raft nodes before linearized reading' (duration: 125.929963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:17:49.978453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.988523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-13T06:17:49.978505Z","caller":"traceutil/trace.go:171","msg":"trace[2057697064] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5446; }","duration":"184.098546ms","start":"2026-04-13T06:17:49.794395Z","end":"2026-04-13T06:17:49.978493Z","steps":["trace[2057697064] 'agreement among raft nodes before linearized reading' (duration: 182.666746ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:18:44.003633Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2916} {"level":"info","ts":"2026-04-13T06:18:44.077293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2916,"took":"71.922344ms","hash":1556194921,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":33468416,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-13T06:18:44.077376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1556194921,"revision":2916,"compact-revision":-1} {"level":"info","ts":"2026-04-13T06:23:03.63283Z","caller":"traceutil/trace.go:171","msg":"trace[1641451433] linearizableReadLoop","detail":"{readStateIndex:8071; appliedIndex:8070; }","duration":"218.409681ms","start":"2026-04-13T06:23:03.414329Z","end":"2026-04-13T06:23:03.632739Z","steps":["trace[1641451433] 'read index received' (duration: 218.279998ms)","trace[1641451433] 'applied index is now lower than readState.Index' (duration: 128.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:23:03.633153Z","caller":"traceutil/trace.go:171","msg":"trace[1812229469] transaction","detail":"{read_only:false; response_revision:7798; number_of_response:1; }","duration":"401.404075ms","start":"2026-04-13T06:23:03.231674Z","end":"2026-04-13T06:23:03.633078Z","steps":["trace[1812229469] 'process raft request' (duration: 400.707886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:23:03.633268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.869894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:23:03.633359Z","caller":"traceutil/trace.go:171","msg":"trace[900604835] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:7798; }","duration":"219.028188ms","start":"2026-04-13T06:23:03.414284Z","end":"2026-04-13T06:23:03.633312Z","steps":["trace[900604835] 'agreement among raft nodes before linearized reading' (duration: 218.826282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:23:03.6334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:23:03.231618Z","time spent":"401.693374ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:23:03.666556Z","caller":"traceutil/trace.go:171","msg":"trace[1880169581] transaction","detail":"{read_only:false; response_revision:7799; number_of_response:1; }","duration":"214.257587ms","start":"2026-04-13T06:23:03.452259Z","end":"2026-04-13T06:23:03.666516Z","steps":["trace[1880169581] 'process raft request' (duration: 201.929091ms)","trace[1880169581] 'compare' (duration: 12.055349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:23:03.66723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.465022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-13T06:23:03.667349Z","caller":"traceutil/trace.go:171","msg":"trace[1747152708] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7800; }","duration":"200.636636ms","start":"2026-04-13T06:23:03.466681Z","end":"2026-04-13T06:23:03.667317Z","steps":["trace[1747152708] 'agreement among raft nodes before linearized reading' (duration: 200.261015ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:23:03.667385Z","caller":"traceutil/trace.go:171","msg":"trace[61098254] transaction","detail":"{read_only:false; response_revision:7800; number_of_response:1; }","duration":"208.770747ms","start":"2026-04-13T06:23:03.458581Z","end":"2026-04-13T06:23:03.667352Z","steps":["trace[61098254] 'process raft request' (duration: 207.866343ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:23:44.012723Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5967} {"level":"info","ts":"2026-04-13T06:23:44.110871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5967,"took":"96.467483ms","hash":3619169836,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":21401600,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-13T06:23:44.110969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3619169836,"revision":5967,"compact-revision":2916} {"level":"info","ts":"2026-04-13T06:24:01.19356Z","caller":"traceutil/trace.go:171","msg":"trace[45653483] linearizableReadLoop","detail":"{readStateIndex:8593; appliedIndex:8592; }","duration":"199.990438ms","start":"2026-04-13T06:24:00.993537Z","end":"2026-04-13T06:24:01.193528Z","steps":["trace[45653483] 'read index received' (duration: 199.770322ms)","trace[45653483] 'applied index is now lower than readState.Index' (duration: 218.276µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:24:01.193697Z","caller":"traceutil/trace.go:171","msg":"trace[15080157] transaction","detail":"{read_only:false; response_revision:8304; number_of_response:1; }","duration":"267.82554ms","start":"2026-04-13T06:24:00.925802Z","end":"2026-04-13T06:24:01.193628Z","steps":["trace[15080157] 'process raft request' (duration: 267.514951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:01.193848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.285316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T06:24:01.193907Z","caller":"traceutil/trace.go:171","msg":"trace[638871807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8304; }","duration":"200.379769ms","start":"2026-04-13T06:24:00.993512Z","end":"2026-04-13T06:24:01.193892Z","steps":["trace[638871807] 'agreement among raft nodes before linearized reading' (duration: 200.190984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:01.193942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.367009ms","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-04-13T06:24:01.193999Z","caller":"traceutil/trace.go:171","msg":"trace[2109869861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8304; }","duration":"196.445801ms","start":"2026-04-13T06:24:00.997539Z","end":"2026-04-13T06:24:01.193985Z","steps":["trace[2109869861] 'agreement among raft nodes before linearized reading' (duration: 196.299597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:01.193975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.761622ms","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-04-13T06:24:01.194065Z","caller":"traceutil/trace.go:171","msg":"trace[2146413876] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8304; }","duration":"195.880656ms","start":"2026-04-13T06:24:00.998163Z","end":"2026-04-13T06:24:01.194044Z","steps":["trace[2146413876] 'agreement among raft nodes before linearized reading' (duration: 195.67252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:01.19456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.248151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:24:01.194666Z","caller":"traceutil/trace.go:171","msg":"trace[1713779626] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8304; }","duration":"198.406356ms","start":"2026-04-13T06:24:00.996236Z","end":"2026-04-13T06:24:01.194643Z","steps":["trace[1713779626] 'agreement among raft nodes before linearized reading' (duration: 197.531632ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:01.428759Z","caller":"traceutil/trace.go:171","msg":"trace[2046118027] transaction","detail":"{read_only:false; response_revision:8306; number_of_response:1; }","duration":"229.917435ms","start":"2026-04-13T06:24:01.198806Z","end":"2026-04-13T06:24:01.428724Z","steps":["trace[2046118027] 'process raft request' (duration: 229.645508ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:01.42891Z","caller":"traceutil/trace.go:171","msg":"trace[1191111588] linearizableReadLoop","detail":"{readStateIndex:8596; appliedIndex:8593; }","duration":"229.780622ms","start":"2026-04-13T06:24:01.199087Z","end":"2026-04-13T06:24:01.428868Z","steps":["trace[1191111588] 'read index received' (duration: 30.603945ms)","trace[1191111588] 'applied index is now lower than readState.Index' (duration: 199.173706ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:24:01.42894Z","caller":"traceutil/trace.go:171","msg":"trace[1342773825] transaction","detail":"{read_only:false; response_revision:8307; number_of_response:1; }","duration":"230.036698ms","start":"2026-04-13T06:24:01.198878Z","end":"2026-04-13T06:24:01.428915Z","steps":["trace[1342773825] 'process raft request' (duration: 229.790442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:01.429171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.060498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19405"} {"level":"info","ts":"2026-04-13T06:24:01.429283Z","caller":"traceutil/trace.go:171","msg":"trace[1882079143] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8307; }","duration":"230.213633ms","start":"2026-04-13T06:24:01.199049Z","end":"2026-04-13T06:24:01.429263Z","steps":["trace[1882079143] 'agreement among raft nodes before linearized reading' (duration: 229.932036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:08.503147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.344134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-45ntx\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-13T06:24:08.503212Z","caller":"traceutil/trace.go:171","msg":"trace[261200678] range","detail":"{range_begin:/registry/pods/openstack/keepalived-45ntx; range_end:; response_count:1; response_revision:8344; }","duration":"146.458757ms","start":"2026-04-13T06:24:08.356741Z","end":"2026-04-13T06:24:08.503199Z","steps":["trace[261200678] 'range keys from in-memory index tree' (duration: 146.205841ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:09.3981Z","caller":"traceutil/trace.go:171","msg":"trace[423427837] linearizableReadLoop","detail":"{readStateIndex:8638; appliedIndex:8637; }","duration":"123.07827ms","start":"2026-04-13T06:24:09.275003Z","end":"2026-04-13T06:24:09.398082Z","steps":["trace[423427837] 'read index received' (duration: 122.905865ms)","trace[423427837] 'applied index is now lower than readState.Index' (duration: 171.685µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:24:09.398247Z","caller":"traceutil/trace.go:171","msg":"trace[1675506076] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"166.380921ms","start":"2026-04-13T06:24:09.231829Z","end":"2026-04-13T06:24:09.39821Z","steps":["trace[1675506076] 'process raft request' (duration: 166.142425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:09.398301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.295095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T06:24:09.398322Z","caller":"traceutil/trace.go:171","msg":"trace[1387611646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8346; }","duration":"123.391848ms","start":"2026-04-13T06:24:09.274925Z","end":"2026-04-13T06:24:09.398316Z","steps":["trace[1387611646] 'agreement among raft nodes before linearized reading' (duration: 123.227503ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:40.650968Z","caller":"traceutil/trace.go:171","msg":"trace[134363853] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"208.734472ms","start":"2026-04-13T06:24:40.442177Z","end":"2026-04-13T06:24:40.650912Z","steps":["trace[134363853] 'process raft request' (duration: 205.647207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:40.935309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.892899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:24:40.935394Z","caller":"traceutil/trace.go:171","msg":"trace[1981351178] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8570; }","duration":"160.039804ms","start":"2026-04-13T06:24:40.775336Z","end":"2026-04-13T06:24:40.935376Z","steps":["trace[1981351178] 'count revisions from in-memory index tree' (duration: 159.770817ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:41.745994Z","caller":"traceutil/trace.go:171","msg":"trace[506670537] transaction","detail":"{read_only:false; response_revision:8572; number_of_response:1; }","duration":"112.308333ms","start":"2026-04-13T06:24:41.63367Z","end":"2026-04-13T06:24:41.745979Z","steps":["trace[506670537] 'process raft request' (duration: 112.025705ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:42.229641Z","caller":"traceutil/trace.go:171","msg":"trace[1370722254] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"165.025379ms","start":"2026-04-13T06:24:42.064537Z","end":"2026-04-13T06:24:42.229562Z","steps":["trace[1370722254] 'process raft request' (duration: 164.871505ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:42.270133Z","caller":"traceutil/trace.go:171","msg":"trace[1843870659] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"174.425926ms","start":"2026-04-13T06:24:42.095678Z","end":"2026-04-13T06:24:42.270104Z","steps":["trace[1843870659] 'process raft request' (duration: 174.22648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:53.094548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.528693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-13T06:24:53.094675Z","caller":"traceutil/trace.go:171","msg":"trace[2066311204] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8678; }","duration":"254.041037ms","start":"2026-04-13T06:24:52.840616Z","end":"2026-04-13T06:24:53.094657Z","steps":["trace[2066311204] 'process raft request' (duration: 110.214596ms)","trace[2066311204] 'compare' (duration: 143.371828ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:24:53.106736Z","caller":"traceutil/trace.go:171","msg":"trace[1531479068] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"265.570741ms","start":"2026-04-13T06:24:52.841127Z","end":"2026-04-13T06:24:53.106698Z","steps":["trace[1531479068] 'process raft request' (duration: 265.320315ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:53.802354Z","caller":"traceutil/trace.go:171","msg":"trace[1536160804] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"195.953043ms","start":"2026-04-13T06:24:53.606373Z","end":"2026-04-13T06:24:53.802326Z","steps":["trace[1536160804] 'process raft request' (duration: 195.793919ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:53.913628Z","caller":"traceutil/trace.go:171","msg":"trace[1350085440] linearizableReadLoop","detail":"{readStateIndex:8988; appliedIndex:8986; }","duration":"153.364803ms","start":"2026-04-13T06:24:53.760235Z","end":"2026-04-13T06:24:53.9136Z","steps":["trace[1350085440] 'read index received' (duration: 41.991185ms)","trace[1350085440] 'applied index is now lower than readState.Index' (duration: 111.372458ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:24:53.91386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.600759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-13T06:24:53.913889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.708282ms","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-04-13T06:24:53.913935Z","caller":"traceutil/trace.go:171","msg":"trace[134964770] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:8682; }","duration":"153.717011ms","start":"2026-04-13T06:24:53.760199Z","end":"2026-04-13T06:24:53.913916Z","steps":["trace[134964770] 'agreement among raft nodes before linearized reading' (duration: 153.580337ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:53.914022Z","caller":"traceutil/trace.go:171","msg":"trace[476136949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8682; }","duration":"105.819465ms","start":"2026-04-13T06:24:53.808139Z","end":"2026-04-13T06:24:53.913959Z","steps":["trace[476136949] 'agreement among raft nodes before linearized reading' (duration: 105.63147ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:53.913759Z","caller":"traceutil/trace.go:171","msg":"trace[1925901384] transaction","detail":"{read_only:false; response_revision:8682; number_of_response:1; }","duration":"295.843007ms","start":"2026-04-13T06:24:53.617904Z","end":"2026-04-13T06:24:53.913747Z","steps":["trace[1925901384] 'process raft request' (duration: 293.943824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:53.914428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.228842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-04-13T06:24:53.914533Z","caller":"traceutil/trace.go:171","msg":"trace[315174971] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8682; }","duration":"104.361565ms","start":"2026-04-13T06:24:53.810144Z","end":"2026-04-13T06:24:53.914506Z","steps":["trace[315174971] 'agreement among raft nodes before linearized reading' (duration: 104.015686ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:54.054291Z","caller":"traceutil/trace.go:171","msg":"trace[1832135291] transaction","detail":"{read_only:false; response_revision:8684; number_of_response:1; }","duration":"134.996461ms","start":"2026-04-13T06:24:53.919262Z","end":"2026-04-13T06:24:54.054258Z","steps":["trace[1832135291] 'process raft request' (duration: 134.750254ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:54.054297Z","caller":"traceutil/trace.go:171","msg":"trace[964416611] transaction","detail":"{read_only:false; response_revision:8685; number_of_response:1; }","duration":"133.955532ms","start":"2026-04-13T06:24:53.920319Z","end":"2026-04-13T06:24:54.054275Z","steps":["trace[964416611] 'process raft request' (duration: 133.86915ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:54.054627Z","caller":"traceutil/trace.go:171","msg":"trace[1685358678] transaction","detail":"{read_only:false; response_revision:8686; number_of_response:1; }","duration":"133.712617ms","start":"2026-04-13T06:24:53.920906Z","end":"2026-04-13T06:24:54.054619Z","steps":["trace[1685358678] 'process raft request' (duration: 133.330906ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:54.054884Z","caller":"traceutil/trace.go:171","msg":"trace[331443064] transaction","detail":"{read_only:false; response_revision:8687; number_of_response:1; }","duration":"126.87765ms","start":"2026-04-13T06:24:53.927965Z","end":"2026-04-13T06:24:54.054842Z","steps":["trace[331443064] 'process raft request' (duration: 126.5362ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:24:54.054871Z","caller":"traceutil/trace.go:171","msg":"trace[1975041394] transaction","detail":"{read_only:false; response_revision:8688; number_of_response:1; }","duration":"113.015591ms","start":"2026-04-13T06:24:53.941821Z","end":"2026-04-13T06:24:54.054837Z","steps":["trace[1975041394] 'process raft request' (duration: 112.887638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:24:54.716108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.853474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:24:54.716298Z","caller":"traceutil/trace.go:171","msg":"trace[1751845585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8695; }","duration":"129.042768ms","start":"2026-04-13T06:24:54.587139Z","end":"2026-04-13T06:24:54.716182Z","steps":["trace[1751845585] 'range keys from in-memory index tree' (duration: 128.72536ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:25:15.264103Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241e.wal"} {"level":"warn","ts":"2026-04-13T06:26:12.790713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.965739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-13T06:26:12.790833Z","caller":"traceutil/trace.go:171","msg":"trace[814001975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9440; }","duration":"267.138545ms","start":"2026-04-13T06:26:12.52367Z","end":"2026-04-13T06:26:12.790808Z","steps":["trace[814001975] 'range keys from in-memory index tree' (duration: 266.828836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:26:12.790851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.138726ms","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-04-13T06:26:12.790906Z","caller":"traceutil/trace.go:171","msg":"trace[1100831113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9440; }","duration":"277.23883ms","start":"2026-04-13T06:26:12.513651Z","end":"2026-04-13T06:26:12.790889Z","steps":["trace[1100831113] 'range keys from in-memory index tree' (duration: 276.741856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:26:12.908244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.916202ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-13T06:26:12.908781Z","caller":"traceutil/trace.go:171","msg":"trace[58620625] transaction","detail":"{read_only:false; response_revision:9441; number_of_response:1; }","duration":"344.70156ms","start":"2026-04-13T06:26:12.564059Z","end":"2026-04-13T06:26:12.908761Z","steps":["trace[58620625] 'process raft request' (duration: 344.298278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:26:12.908922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:26:12.564019Z","time spent":"344.822353ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:26:12.908492Z","caller":"traceutil/trace.go:171","msg":"trace[398770972] linearizableReadLoop","detail":"{readStateIndex:9768; appliedIndex:9767; }","duration":"368.872938ms","start":"2026-04-13T06:26:12.539597Z","end":"2026-04-13T06:26:12.90847Z","steps":["trace[398770972] 'read index received' (duration: 223.661929ms)","trace[398770972] 'applied index is now lower than readState.Index' (duration: 145.209459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:26:12.97525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.620968ms","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-04-13T06:26:12.975399Z","caller":"traceutil/trace.go:171","msg":"trace[1596535726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9441; }","duration":"435.776752ms","start":"2026-04-13T06:26:12.539593Z","end":"2026-04-13T06:26:12.97537Z","steps":["trace[1596535726] 'agreement among raft nodes before linearized reading' (duration: 369.105595ms)","trace[1596535726] 'range keys from in-memory index tree' (duration: 66.375309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T06:26:12.97546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:26:12.539557Z","time spent":"435.887245ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T06:26:12.975733Z","caller":"traceutil/trace.go:171","msg":"trace[455169530] transaction","detail":"{read_only:false; response_revision:9442; number_of_response:1; }","duration":"316.592372ms","start":"2026-04-13T06:26:12.65909Z","end":"2026-04-13T06:26:12.975682Z","steps":["trace[455169530] 'process raft request' (duration: 249.657007ms)","trace[455169530] 'compare' (duration: 66.299637ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:26:12.975834Z","caller":"traceutil/trace.go:171","msg":"trace[1702168218] transaction","detail":"{read_only:false; response_revision:9443; number_of_response:1; }","duration":"314.917576ms","start":"2026-04-13T06:26:12.660832Z","end":"2026-04-13T06:26:12.97575Z","steps":["trace[1702168218] 'process raft request' (duration: 314.642038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:26:12.975965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:26:12.659069Z","time spent":"316.773398ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T06:26:12.976059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:26:12.660814Z","time spent":"315.141353ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T06:26:12.976099Z","caller":"traceutil/trace.go:171","msg":"trace[502609097] transaction","detail":"{read_only:false; response_revision:9444; number_of_response:1; }","duration":"187.392869ms","start":"2026-04-13T06:26:12.788683Z","end":"2026-04-13T06:26:12.976076Z","steps":["trace[502609097] 'process raft request' (duration: 186.895235ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:26:12.976345Z","caller":"traceutil/trace.go:171","msg":"trace[1070489266] transaction","detail":"{read_only:false; response_revision:9445; number_of_response:1; }","duration":"179.408942ms","start":"2026-04-13T06:26:12.796861Z","end":"2026-04-13T06:26:12.97627Z","steps":["trace[1070489266] 'process raft request' (duration: 178.802656ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:26:12.976393Z","caller":"traceutil/trace.go:171","msg":"trace[1938472843] transaction","detail":"{read_only:false; response_revision:9446; number_of_response:1; }","duration":"179.024712ms","start":"2026-04-13T06:26:12.797329Z","end":"2026-04-13T06:26:12.976353Z","steps":["trace[1938472843] 'process raft request' (duration: 178.787775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:26:12.97657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.934787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T06:26:12.976686Z","caller":"traceutil/trace.go:171","msg":"trace[1084611702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9446; }","duration":"392.09522ms","start":"2026-04-13T06:26:12.584564Z","end":"2026-04-13T06:26:12.976659Z","steps":["trace[1084611702] 'agreement among raft nodes before linearized reading' (duration: 391.67919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:26:12.976784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T06:26:12.584552Z","time spent":"392.213743ms","remote":"127.0.0.1:41218","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-04-13T06:26:44.837077Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T06:26:44.85383Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T06:26:44.853957Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T06:28:44.019823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8077} {"level":"info","ts":"2026-04-13T06:28:44.083555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8077,"took":"61.345283ms","hash":1246153138,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":27852800,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-13T06:28:44.083659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1246153138,"revision":8077,"compact-revision":5967} {"level":"info","ts":"2026-04-13T06:33:28.91011Z","caller":"traceutil/trace.go:171","msg":"trace[1609915211] transaction","detail":"{read_only:false; response_revision:12081; number_of_response:1; }","duration":"173.013984ms","start":"2026-04-13T06:33:28.73706Z","end":"2026-04-13T06:33:28.910074Z","steps":["trace[1609915211] 'process raft request' (duration: 172.818278ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:33:28.911303Z","caller":"traceutil/trace.go:171","msg":"trace[66911016] transaction","detail":"{read_only:false; response_revision:12082; number_of_response:1; }","duration":"172.832988ms","start":"2026-04-13T06:33:28.738435Z","end":"2026-04-13T06:33:28.911268Z","steps":["trace[66911016] 'process raft request' (duration: 172.53893ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:33:28.911468Z","caller":"traceutil/trace.go:171","msg":"trace[657708956] linearizableReadLoop","detail":"{readStateIndex:12507; appliedIndex:12504; }","duration":"158.053565ms","start":"2026-04-13T06:33:28.753391Z","end":"2026-04-13T06:33:28.911444Z","steps":["trace[657708956] 'read index received' (duration: 156.596846ms)","trace[657708956] 'applied index is now lower than readState.Index' (duration: 1.454149ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T06:33:28.9115Z","caller":"traceutil/trace.go:171","msg":"trace[1278257398] transaction","detail":"{read_only:false; response_revision:12083; number_of_response:1; }","duration":"170.752492ms","start":"2026-04-13T06:33:28.74073Z","end":"2026-04-13T06:33:28.911482Z","steps":["trace[1278257398] 'process raft request' (duration: 170.485914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T06:33:28.911657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.2398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T06:33:28.91172Z","caller":"traceutil/trace.go:171","msg":"trace[817769471] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12083; }","duration":"158.330783ms","start":"2026-04-13T06:33:28.753372Z","end":"2026-04-13T06:33:28.911703Z","steps":["trace[817769471] 'agreement among raft nodes before linearized reading' (duration: 158.20056ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T06:33:44.025893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10440} {"level":"info","ts":"2026-04-13T06:33:44.10804Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10440,"took":"79.488735ms","hash":3398300733,"current-db-size-bytes":58044416,"current-db-size":"58 MB","current-db-size-in-use-bytes":21327872,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-13T06:33:44.108098Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3398300733,"revision":10440,"compact-revision":8077}