{"level":"warn","ts":"2026-08-26T03:56:09.407347Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T03:56:09.407436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.71: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.71:2380","--initial-cluster=instance=https://162.253.55.71:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.71:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.71: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-08-26T03:56:09.407505Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T03:56:09.407519Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-08-26T03:56:09.40754Z","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-08-26T03:56:09.408325Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"]} {"level":"info","ts":"2026-08-26T03:56:09.408417Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.71: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-08-26T03:56:09.413073Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.421042ms"} {"level":"info","ts":"2026-08-26T03:56:09.418733Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54232768b5552f01","cluster-id":"66db39a47e9fb23b"} {"level":"info","ts":"2026-08-26T03:56:09.418829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T03:56:09.418858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 0"} {"level":"info","ts":"2026-08-26T03:56:09.418876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54232768b5552f01 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T03:56:09.418891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became follower at term 1"} {"level":"info","ts":"2026-08-26T03:56:09.418997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"warn","ts":"2026-08-26T03:56:09.427257Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T03:56:09.428964Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T03:56:09.430703Z","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-08-26T03:56:09.43276Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54232768b5552f01","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T03:56:09.432915Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54232768b5552f01","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T03:56:09.433089Z","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-08-26T03:56:09.4334Z","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-08-26T03:56:09.433412Z","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-08-26T03:56:09.433776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 switched to configuration voters=(6062732854043160321)"} {"level":"info","ts":"2026-08-26T03:56:09.433984Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","added-peer-id":"54232768b5552f01","added-peer-peer-urls":["https://162.253.55.71:2380"]} {"level":"info","ts":"2026-08-26T03:56:09.441194Z","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-08-26T03:56:09.441253Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-08-26T03:56:09.441266Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.71:2380"} {"level":"info","ts":"2026-08-26T03:56:09.442127Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54232768b5552f01","initial-advertise-peer-urls":["https://162.253.55.71:2380"],"listen-peer-urls":["https://162.253.55.71:2380"],"advertise-client-urls":["https://162.253.55.71:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.71:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T03:56:09.442158Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T03:56:09.620297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T03:56:09.620434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T03:56:09.620503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgPreVoteResp from 54232768b5552f01 at term 1"} {"level":"info","ts":"2026-08-26T03:56:09.62056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became candidate at term 2"} {"level":"info","ts":"2026-08-26T03:56:09.620594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 received MsgVoteResp from 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-08-26T03:56:09.620635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54232768b5552f01 became leader at term 2"} {"level":"info","ts":"2026-08-26T03:56:09.620684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54232768b5552f01 elected leader 54232768b5552f01 at term 2"} {"level":"info","ts":"2026-08-26T03:56:09.622726Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54232768b5552f01","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.71:2379]}","request-path":"/0/members/54232768b5552f01/attributes","cluster-id":"66db39a47e9fb23b","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T03:56:09.622817Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T03:56:09.623056Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T03:56:09.623113Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T03:56:09.623113Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T03:56:09.623044Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T03:56:09.624381Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"66db39a47e9fb23b","local-member-id":"54232768b5552f01","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T03:56:09.624585Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T03:56:09.624625Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T03:56:09.62687Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T03:56:09.626907Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.71:2379"} {"level":"info","ts":"2026-08-26T03:56:12.393544Z","caller":"traceutil/trace.go:171","msg":"trace[2123353247] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"185.993496ms","start":"2026-08-26T03:56:12.207526Z","end":"2026-08-26T03:56:12.393519Z","steps":["trace[2123353247] 'read index received' (duration: 185.772254ms)","trace[2123353247] 'applied index is now lower than readState.Index' (duration: 220.442µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:56:12.393632Z","caller":"traceutil/trace.go:171","msg":"trace[2000449043] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"187.232423ms","start":"2026-08-26T03:56:12.206362Z","end":"2026-08-26T03:56:12.393594Z","steps":["trace[2000449043] 'process raft request' (duration: 187.033972ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:12.393771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.182787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-26T03:56:12.393857Z","caller":"traceutil/trace.go:171","msg":"trace[1913184025] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"186.339658ms","start":"2026-08-26T03:56:12.2075Z","end":"2026-08-26T03:56:12.393839Z","steps":["trace[1913184025] 'agreement among raft nodes before linearized reading' (duration: 186.132766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:12.769579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.97284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-26T03:56:12.769694Z","caller":"traceutil/trace.go:171","msg":"trace[1176548102] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"369.832364ms","start":"2026-08-26T03:56:12.399846Z","end":"2026-08-26T03:56:12.769679Z","steps":["trace[1176548102] 'read index received' (duration: 158.379931ms)","trace[1176548102] 'applied index is now lower than readState.Index' (duration: 211.451253ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:56:12.76972Z","caller":"traceutil/trace.go:171","msg":"trace[1707741105] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"371.455655ms","start":"2026-08-26T03:56:12.398247Z","end":"2026-08-26T03:56:12.769702Z","steps":["trace[1707741105] 'process raft request' (duration: 159.874782ms)","trace[1707741105] 'compare' (duration: 210.789978ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:56:12.770213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:12.398229Z","time spent":"371.926888ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T03:56:12.769886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.997275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-26T03:56:12.770273Z","caller":"traceutil/trace.go:171","msg":"trace[317478711] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:73; }","duration":"370.437538ms","start":"2026-08-26T03:56:12.399824Z","end":"2026-08-26T03:56:12.770262Z","steps":["trace[317478711] 'agreement among raft nodes before linearized reading' (duration: 369.969525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:12.77036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:12.399814Z","time spent":"370.534599ms","remote":"127.0.0.1:45580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-08-26T03:56:43.847147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.176854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T03:56:43.847376Z","caller":"traceutil/trace.go:171","msg":"trace[2018287628] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"136.234471ms","start":"2026-08-26T03:56:43.711125Z","end":"2026-08-26T03:56:43.847359Z","steps":["trace[2018287628] 'read index received' (duration: 30.98µs)","trace[2018287628] 'applied index is now lower than readState.Index' (duration: 136.202151ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:56:43.847614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.488723ms","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-08-26T03:56:43.847709Z","caller":"traceutil/trace.go:171","msg":"trace[430266667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"136.584423ms","start":"2026-08-26T03:56:43.711113Z","end":"2026-08-26T03:56:43.847697Z","steps":["trace[430266667] 'agreement among raft nodes before linearized reading' (duration: 136.420952ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:56:43.847594Z","caller":"traceutil/trace.go:171","msg":"trace[1349581093] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"208.444597ms","start":"2026-08-26T03:56:43.639103Z","end":"2026-08-26T03:56:43.847548Z","steps":["trace[1349581093] 'process raft request' (duration: 69.64694ms)","trace[1349581093] 'compare' (duration: 138.060793ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:56:47.708164Z","caller":"traceutil/trace.go:171","msg":"trace[1608632514] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"157.601433ms","start":"2026-08-26T03:56:47.550537Z","end":"2026-08-26T03:56:47.708138Z","steps":["trace[1608632514] 'process raft request' (duration: 114.183704ms)","trace[1608632514] 'compare' (duration: 43.328838ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:56:47.709626Z","caller":"traceutil/trace.go:171","msg":"trace[526530393] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"124.222329ms","start":"2026-08-26T03:56:47.585379Z","end":"2026-08-26T03:56:47.709601Z","steps":["trace[526530393] 'process raft request' (duration: 124.165298ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:56:47.709992Z","caller":"traceutil/trace.go:171","msg":"trace[559392307] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"151.544034ms","start":"2026-08-26T03:56:47.558413Z","end":"2026-08-26T03:56:47.709957Z","steps":["trace[559392307] 'process raft request' (duration: 150.90355ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:56:47.867189Z","caller":"traceutil/trace.go:171","msg":"trace[150995679] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"144.75179ms","start":"2026-08-26T03:56:47.722408Z","end":"2026-08-26T03:56:47.86716Z","steps":["trace[150995679] 'process raft request' (duration: 134.816696ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:56:47.870862Z","caller":"traceutil/trace.go:171","msg":"trace[1424448920] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"104.920084ms","start":"2026-08-26T03:56:47.765861Z","end":"2026-08-26T03:56:47.870781Z","steps":["trace[1424448920] 'process raft request' (duration: 101.873445ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:56:47.871361Z","caller":"traceutil/trace.go:171","msg":"trace[2100946936] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"102.092096ms","start":"2026-08-26T03:56:47.76615Z","end":"2026-08-26T03:56:47.868242Z","steps":["trace[2100946936] 'process raft request' (duration: 101.761204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:49.351575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.306744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T03:56:49.351642Z","caller":"traceutil/trace.go:171","msg":"trace[1585982133] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"433.928332ms","start":"2026-08-26T03:56:48.917702Z","end":"2026-08-26T03:56:49.351631Z","steps":["trace[1585982133] 'process raft request' (duration: 49.501838ms)","trace[1585982133] 'compare' (duration: 384.217183ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:56:49.351673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:48.917676Z","time spent":"433.982373ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T03:56:49.354159Z","caller":"traceutil/trace.go:171","msg":"trace[15455448] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"429.087861ms","start":"2026-08-26T03:56:48.925034Z","end":"2026-08-26T03:56:49.354122Z","steps":["trace[15455448] 'read index received' (duration: 42.05458ms)","trace[15455448] 'applied index is now lower than readState.Index' (duration: 387.031911ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:56:49.354544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.468774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-08-26T03:56:49.354591Z","caller":"traceutil/trace.go:171","msg":"trace[88857517] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:603; }","duration":"429.630854ms","start":"2026-08-26T03:56:48.924942Z","end":"2026-08-26T03:56:49.354573Z","steps":["trace[88857517] 'agreement among raft nodes before linearized reading' (duration: 429.333822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:49.354634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:48.924764Z","time spent":"429.859746ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80725,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-26T03:56:49.354814Z","caller":"traceutil/trace.go:171","msg":"trace[1198952942] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"431.148144ms","start":"2026-08-26T03:56:48.923647Z","end":"2026-08-26T03:56:49.354795Z","steps":["trace[1198952942] 'process raft request' (duration: 429.756966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:49.354891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:48.92362Z","time spent":"431.234406ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T03:56:54.828452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.521362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T03:56:54.828644Z","caller":"traceutil/trace.go:171","msg":"trace[999757426] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"396.027511ms","start":"2026-08-26T03:56:54.4326Z","end":"2026-08-26T03:56:54.828627Z","steps":["trace[999757426] 'read index received' (duration: 144.986732ms)","trace[999757426] 'applied index is now lower than readState.Index' (duration: 251.039239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:56:54.82878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.174309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T03:56:54.82886Z","caller":"traceutil/trace.go:171","msg":"trace[800153416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"396.283224ms","start":"2026-08-26T03:56:54.432565Z","end":"2026-08-26T03:56:54.828848Z","steps":["trace[800153416] 'agreement among raft nodes before linearized reading' (duration: 396.177229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:54.828923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:54.432543Z","time spent":"396.369629ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T03:56:54.828748Z","caller":"traceutil/trace.go:171","msg":"trace[80677119] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"526.453214ms","start":"2026-08-26T03:56:54.302253Z","end":"2026-08-26T03:56:54.828707Z","steps":["trace[80677119] 'process raft request' (duration: 275.596415ms)","trace[80677119] 'compare' (duration: 250.182704ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:56:54.829074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.617536ms","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-08-26T03:56:54.829163Z","caller":"traceutil/trace.go:171","msg":"trace[2132736040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"312.753162ms","start":"2026-08-26T03:56:54.51639Z","end":"2026-08-26T03:56:54.829144Z","steps":["trace[2132736040] 'agreement among raft nodes before linearized reading' (duration: 312.499919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:56:54.829207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:54.516367Z","time spent":"312.831307ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T03:56:54.829191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:56:54.302229Z","time spent":"526.840214ms","remote":"127.0.0.1:45328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T03:56:54.97623Z","caller":"traceutil/trace.go:171","msg":"trace[1931474367] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"142.869085ms","start":"2026-08-26T03:56:54.833337Z","end":"2026-08-26T03:56:54.976206Z","steps":["trace[1931474367] 'process raft request' (duration: 142.706837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:57:09.380517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.310928ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-26T03:57:09.380636Z","caller":"traceutil/trace.go:171","msg":"trace[1843457429] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"345.811274ms","start":"2026-08-26T03:57:09.034805Z","end":"2026-08-26T03:57:09.380617Z","steps":["trace[1843457429] 'read index received' (duration: 128.288037ms)","trace[1843457429] 'applied index is now lower than readState.Index' (duration: 217.521827ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:57:09.380857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.046995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-26T03:57:09.380891Z","caller":"traceutil/trace.go:171","msg":"trace[2114470554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"346.122389ms","start":"2026-08-26T03:57:09.034756Z","end":"2026-08-26T03:57:09.380879Z","steps":["trace[2114470554] 'agreement among raft nodes before linearized reading' (duration: 345.95246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:57:09.380927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:57:09.034739Z","time spent":"346.180811ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-26T03:58:01.78471Z","caller":"traceutil/trace.go:171","msg":"trace[215041976] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"136.035497ms","start":"2026-08-26T03:58:01.648649Z","end":"2026-08-26T03:58:01.784684Z","steps":["trace[215041976] 'process raft request' (duration: 135.876313ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:58:06.474392Z","caller":"traceutil/trace.go:171","msg":"trace[712542425] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"100.706622ms","start":"2026-08-26T03:58:06.373653Z","end":"2026-08-26T03:58:06.47436Z","steps":["trace[712542425] 'process raft request' (duration: 81.802472ms)","trace[712542425] 'compare' (duration: 18.746966ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:58:06.653352Z","caller":"traceutil/trace.go:171","msg":"trace[1592872541] transaction","detail":"{read_only:false; response_revision:1551; number_of_response:1; }","duration":"111.569497ms","start":"2026-08-26T03:58:06.54175Z","end":"2026-08-26T03:58:06.653319Z","steps":["trace[1592872541] 'process raft request' (duration: 98.314044ms)","trace[1592872541] 'compare' (duration: 12.863353ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:58:26.370817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.199577ms","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-08-26T03:58:26.370919Z","caller":"traceutil/trace.go:171","msg":"trace[2140073744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1873; }","duration":"458.34767ms","start":"2026-08-26T03:58:25.912552Z","end":"2026-08-26T03:58:26.3709Z","steps":["trace[2140073744] 'range keys from in-memory index tree' (duration: 458.025864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:58:26.370977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:58:25.912531Z","time spent":"458.436802ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T03:58:26.371285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.20341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b258f2c-0b66-4cd7-b0af-d91130a6d1e4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-26T03:58:26.371376Z","caller":"traceutil/trace.go:171","msg":"trace[129205521] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0b258f2c-0b66-4cd7-b0af-d91130a6d1e4; range_end:; response_count:1; response_revision:1873; }","duration":"313.350463ms","start":"2026-08-26T03:58:26.058003Z","end":"2026-08-26T03:58:26.371353Z","steps":["trace[129205521] 'range keys from in-memory index tree' (duration: 313.036966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:58:26.371427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:58:26.057974Z","time spent":"313.442796ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b258f2c-0b66-4cd7-b0af-d91130a6d1e4\" "} {"level":"warn","ts":"2026-08-26T03:58:26.371652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.112626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T03:58:26.371719Z","caller":"traceutil/trace.go:171","msg":"trace[936809397] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"172.739542ms","start":"2026-08-26T03:58:26.198968Z","end":"2026-08-26T03:58:26.371708Z","steps":["trace[936809397] 'read index received' (duration: 27.445032ms)","trace[936809397] 'applied index is now lower than readState.Index' (duration: 145.29331ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:58:26.372006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.028959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T03:58:26.372101Z","caller":"traceutil/trace.go:171","msg":"trace[1839174209] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1874; }","duration":"173.154741ms","start":"2026-08-26T03:58:26.198928Z","end":"2026-08-26T03:58:26.372083Z","steps":["trace[1839174209] 'agreement among raft nodes before linearized reading' (duration: 172.909996ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:58:26.372261Z","caller":"traceutil/trace.go:171","msg":"trace[2032743740] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"285.201838ms","start":"2026-08-26T03:58:26.087031Z","end":"2026-08-26T03:58:26.372233Z","steps":["trace[2032743740] 'process raft request' (duration: 139.421828ms)","trace[2032743740] 'compare' (duration: 144.500223ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:58:26.877195Z","caller":"traceutil/trace.go:171","msg":"trace[2111895108] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"149.083279ms","start":"2026-08-26T03:58:26.728092Z","end":"2026-08-26T03:58:26.877176Z","steps":["trace[2111895108] 'read index received' (duration: 149.007198ms)","trace[2111895108] 'applied index is now lower than readState.Index' (duration: 75.171µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:58:26.877267Z","caller":"traceutil/trace.go:171","msg":"trace[424443032] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"279.957178ms","start":"2026-08-26T03:58:26.597263Z","end":"2026-08-26T03:58:26.87722Z","steps":["trace[424443032] 'process raft request' (duration: 279.752714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:58:26.877377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.266913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-0b258f2c-0b66-4cd7-b0af-d91130a6d1e4\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-26T03:58:26.877438Z","caller":"traceutil/trace.go:171","msg":"trace[856576811] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-0b258f2c-0b66-4cd7-b0af-d91130a6d1e4; range_end:; response_count:1; response_revision:1877; }","duration":"149.391545ms","start":"2026-08-26T03:58:26.728035Z","end":"2026-08-26T03:58:26.877427Z","steps":["trace[856576811] 'agreement among raft nodes before linearized reading' (duration: 149.238442ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:58:26.878674Z","caller":"traceutil/trace.go:171","msg":"trace[1408204339] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"148.16693ms","start":"2026-08-26T03:58:26.730495Z","end":"2026-08-26T03:58:26.878662Z","steps":["trace[1408204339] 'process raft request' (duration: 147.889973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:58:26.878904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.853186ms","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-08-26T03:58:26.879004Z","caller":"traceutil/trace.go:171","msg":"trace[421527022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1878; }","duration":"127.005649ms","start":"2026-08-26T03:58:26.751981Z","end":"2026-08-26T03:58:26.878987Z","steps":["trace[421527022] 'agreement among raft nodes before linearized reading' (duration: 126.601721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:58:26.879189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.918031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-26T03:58:26.880477Z","caller":"traceutil/trace.go:171","msg":"trace[1927039505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1878; }","duration":"121.238267ms","start":"2026-08-26T03:58:26.759221Z","end":"2026-08-26T03:58:26.880459Z","steps":["trace[1927039505] 'agreement among raft nodes before linearized reading' (duration: 119.718226ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:58:40.993234Z","caller":"traceutil/trace.go:171","msg":"trace[1761891088] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"153.2554ms","start":"2026-08-26T03:58:40.839957Z","end":"2026-08-26T03:58:40.993213Z","steps":["trace[1761891088] 'process raft request' (duration: 153.111777ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:58:43.891206Z","caller":"traceutil/trace.go:171","msg":"trace[513728579] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"110.763945ms","start":"2026-08-26T03:58:43.780417Z","end":"2026-08-26T03:58:43.891181Z","steps":["trace[513728579] 'process raft request' (duration: 110.631911ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:59:00.671427Z","caller":"traceutil/trace.go:171","msg":"trace[800714227] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"152.609656ms","start":"2026-08-26T03:59:00.518795Z","end":"2026-08-26T03:59:00.671405Z","steps":["trace[800714227] 'process raft request' (duration: 152.081416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.089735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.432421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T03:59:01.0899Z","caller":"traceutil/trace.go:171","msg":"trace[2075397086] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"405.603603ms","start":"2026-08-26T03:59:00.68428Z","end":"2026-08-26T03:59:01.089884Z","steps":["trace[2075397086] 'read index received' (duration: 193.011429ms)","trace[2075397086] 'applied index is now lower than readState.Index' (duration: 212.591214ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:59:01.089996Z","caller":"traceutil/trace.go:171","msg":"trace[1332423687] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"414.368701ms","start":"2026-08-26T03:59:00.675616Z","end":"2026-08-26T03:59:01.089984Z","steps":["trace[1332423687] 'process raft request' (duration: 201.606354ms)","trace[1332423687] 'compare' (duration: 212.33228ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:59:01.090059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:59:00.675592Z","time spent":"414.435392ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T03:59:01.090217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.940638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T03:59:01.090254Z","caller":"traceutil/trace.go:171","msg":"trace[877157363] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2114; }","duration":"406.0348ms","start":"2026-08-26T03:59:00.68421Z","end":"2026-08-26T03:59:01.090245Z","steps":["trace[877157363] 'agreement among raft nodes before linearized reading' (duration: 405.949209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.090287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:59:00.684193Z","time spent":"406.084851ms","remote":"127.0.0.1:55140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-08-26T03:59:01.090312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.094522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T03:59:01.090369Z","caller":"traceutil/trace.go:171","msg":"trace[1903933089] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2114; }","duration":"360.208943ms","start":"2026-08-26T03:59:00.730146Z","end":"2026-08-26T03:59:01.090355Z","steps":["trace[1903933089] 'agreement among raft nodes before linearized reading' (duration: 360.097961ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.090394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:59:00.730131Z","time spent":"360.257524ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-08-26T03:59:01.090744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.284294ms","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-08-26T03:59:01.090821Z","caller":"traceutil/trace.go:171","msg":"trace[992151143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2114; }","duration":"393.396385ms","start":"2026-08-26T03:59:00.697409Z","end":"2026-08-26T03:59:01.090805Z","steps":["trace[992151143] 'agreement among raft nodes before linearized reading' (duration: 392.855386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.091053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.168613ms","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-08-26T03:59:01.091102Z","caller":"traceutil/trace.go:171","msg":"trace[1527018650] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"399.242734ms","start":"2026-08-26T03:59:00.691847Z","end":"2026-08-26T03:59:01.09109Z","steps":["trace[1527018650] 'agreement among raft nodes before linearized reading' (duration: 398.37855ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.091125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:59:00.691827Z","time spent":"399.293545ms","remote":"127.0.0.1:39540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-26T03:59:01.090871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:59:00.697364Z","time spent":"393.493957ms","remote":"127.0.0.1:45504","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-08-26T03:59:01.355199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.778758ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T03:59:01.355346Z","caller":"traceutil/trace.go:171","msg":"trace[1299333569] linearizableReadLoop","detail":"{readStateIndex:2188; appliedIndex:2187; }","duration":"194.794613ms","start":"2026-08-26T03:59:01.160529Z","end":"2026-08-26T03:59:01.355324Z","steps":["trace[1299333569] 'read index received' (duration: 90.72966ms)","trace[1299333569] 'applied index is now lower than readState.Index' (duration: 104.063223ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:59:01.355602Z","caller":"traceutil/trace.go:171","msg":"trace[789404448] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"260.265556ms","start":"2026-08-26T03:59:01.095316Z","end":"2026-08-26T03:59:01.355582Z","steps":["trace[789404448] 'process raft request' (duration: 156.00176ms)","trace[789404448] 'compare' (duration: 103.644316ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T03:59:01.355804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.019957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T03:59:01.355904Z","caller":"traceutil/trace.go:171","msg":"trace[2043513995] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2115; }","duration":"182.162859ms","start":"2026-08-26T03:59:01.173716Z","end":"2026-08-26T03:59:01.355879Z","steps":["trace[2043513995] 'agreement among raft nodes before linearized reading' (duration: 181.993517ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.355959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.973076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-26T03:59:01.356042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.162719ms","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-08-26T03:59:01.356093Z","caller":"traceutil/trace.go:171","msg":"trace[580993364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2115; }","duration":"192.23276ms","start":"2026-08-26T03:59:01.163847Z","end":"2026-08-26T03:59:01.356079Z","steps":["trace[580993364] 'agreement among raft nodes before linearized reading' (duration: 192.102077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.356036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.502305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-26T03:59:01.356211Z","caller":"traceutil/trace.go:171","msg":"trace[81030392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2115; }","duration":"195.719638ms","start":"2026-08-26T03:59:01.160475Z","end":"2026-08-26T03:59:01.356194Z","steps":["trace[81030392] 'agreement among raft nodes before linearized reading' (duration: 195.275231ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:59:01.35613Z","caller":"traceutil/trace.go:171","msg":"trace[279806785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2115; }","duration":"195.191629ms","start":"2026-08-26T03:59:01.16088Z","end":"2026-08-26T03:59:01.356072Z","steps":["trace[279806785] 'agreement among raft nodes before linearized reading' (duration: 194.837384ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:59:01.694892Z","caller":"traceutil/trace.go:171","msg":"trace[1385929777] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"333.439449ms","start":"2026-08-26T03:59:01.361421Z","end":"2026-08-26T03:59:01.69486Z","steps":["trace[1385929777] 'process raft request' (duration: 333.247215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.695056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:59:01.361413Z","time spent":"333.55238ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T03:59:01.695222Z","caller":"traceutil/trace.go:171","msg":"trace[1857526904] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"333.638902ms","start":"2026-08-26T03:59:01.361555Z","end":"2026-08-26T03:59:01.695194Z","steps":["trace[1857526904] 'process raft request' (duration: 333.260086ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.69539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.205212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T03:59:01.695443Z","caller":"traceutil/trace.go:171","msg":"trace[420372715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2118; }","duration":"264.349744ms","start":"2026-08-26T03:59:01.431082Z","end":"2026-08-26T03:59:01.695431Z","steps":["trace[420372715] 'agreement among raft nodes before linearized reading' (duration: 264.200131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.695416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T03:59:01.361539Z","time spent":"333.796954ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T03:59:01.695197Z","caller":"traceutil/trace.go:171","msg":"trace[402952458] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2189; }","duration":"264.022389ms","start":"2026-08-26T03:59:01.43112Z","end":"2026-08-26T03:59:01.695142Z","steps":["trace[402952458] 'read index received' (duration: 263.611052ms)","trace[402952458] 'applied index is now lower than readState.Index' (duration: 409.087µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T03:59:01.697629Z","caller":"traceutil/trace.go:171","msg":"trace[1045545547] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"118.575208ms","start":"2026-08-26T03:59:01.579028Z","end":"2026-08-26T03:59:01.697603Z","steps":["trace[1045545547] 'process raft request' (duration: 118.362204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T03:59:01.69766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.819507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T03:59:01.697714Z","caller":"traceutil/trace.go:171","msg":"trace[1937703549] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2119; }","duration":"250.894878ms","start":"2026-08-26T03:59:01.446805Z","end":"2026-08-26T03:59:01.6977Z","steps":["trace[1937703549] 'agreement among raft nodes before linearized reading' (duration: 250.739766ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:59:56.284999Z","caller":"traceutil/trace.go:171","msg":"trace[2049208619] transaction","detail":"{read_only:false; response_revision:2463; number_of_response:1; }","duration":"122.297046ms","start":"2026-08-26T03:59:56.16267Z","end":"2026-08-26T03:59:56.284967Z","steps":["trace[2049208619] 'process raft request' (duration: 122.119734ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:59:56.290019Z","caller":"traceutil/trace.go:171","msg":"trace[615758652] transaction","detail":"{read_only:false; response_revision:2464; number_of_response:1; }","duration":"127.257124ms","start":"2026-08-26T03:59:56.162739Z","end":"2026-08-26T03:59:56.289996Z","steps":["trace[615758652] 'process raft request' (duration: 127.088282ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:59:56.290303Z","caller":"traceutil/trace.go:171","msg":"trace[1560310376] transaction","detail":"{read_only:false; response_revision:2465; number_of_response:1; }","duration":"127.433216ms","start":"2026-08-26T03:59:56.162852Z","end":"2026-08-26T03:59:56.290285Z","steps":["trace[1560310376] 'process raft request' (duration: 127.106892ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T03:59:56.290303Z","caller":"traceutil/trace.go:171","msg":"trace[983576825] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"127.364065ms","start":"2026-08-26T03:59:56.16291Z","end":"2026-08-26T03:59:56.290275Z","steps":["trace[983576825] 'process raft request' (duration: 127.233553ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:00:05.4576Z","caller":"traceutil/trace.go:171","msg":"trace[416560752] transaction","detail":"{read_only:false; response_revision:2564; number_of_response:1; }","duration":"136.706341ms","start":"2026-08-26T04:00:05.320857Z","end":"2026-08-26T04:00:05.457564Z","steps":["trace[416560752] 'process raft request' (duration: 136.556459ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:00:05.638433Z","caller":"traceutil/trace.go:171","msg":"trace[1817562171] transaction","detail":"{read_only:false; response_revision:2565; number_of_response:1; }","duration":"175.735344ms","start":"2026-08-26T04:00:05.462676Z","end":"2026-08-26T04:00:05.638412Z","steps":["trace[1817562171] 'process raft request' (duration: 167.300091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:00:37.895769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.605094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T04:00:37.89587Z","caller":"traceutil/trace.go:171","msg":"trace[807303724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2742; }","duration":"102.779036ms","start":"2026-08-26T04:00:37.793068Z","end":"2026-08-26T04:00:37.895847Z","steps":["trace[807303724] 'range keys from in-memory index tree' (duration: 102.27763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:00:37.895874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.351475ms","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-08-26T04:00:37.895963Z","caller":"traceutil/trace.go:171","msg":"trace[2045487749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2742; }","duration":"100.526758ms","start":"2026-08-26T04:00:37.795416Z","end":"2026-08-26T04:00:37.895943Z","steps":["trace[2045487749] 'range keys from in-memory index tree' (duration: 100.000581ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:00:38.071663Z","caller":"traceutil/trace.go:171","msg":"trace[566997651] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"171.165048ms","start":"2026-08-26T04:00:37.900461Z","end":"2026-08-26T04:00:38.071626Z","steps":["trace[566997651] 'process raft request' (duration: 170.966836ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:00:38.168952Z","caller":"traceutil/trace.go:171","msg":"trace[756942555] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"268.172148ms","start":"2026-08-26T04:00:37.900741Z","end":"2026-08-26T04:00:38.168913Z","steps":["trace[756942555] 'process raft request' (duration: 267.878395ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:00:38.169105Z","caller":"traceutil/trace.go:171","msg":"trace[865954912] linearizableReadLoop","detail":"{readStateIndex:2840; appliedIndex:2837; }","duration":"198.074784ms","start":"2026-08-26T04:00:37.971004Z","end":"2026-08-26T04:00:38.169079Z","steps":["trace[865954912] 'read index received' (duration: 100.457776ms)","trace[865954912] 'applied index is now lower than readState.Index' (duration: 97.614808ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T04:00:38.169181Z","caller":"traceutil/trace.go:171","msg":"trace[1455107163] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"160.535221ms","start":"2026-08-26T04:00:38.008635Z","end":"2026-08-26T04:00:38.169171Z","steps":["trace[1455107163] 'process raft request' (duration: 160.233168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:00:38.169414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.359618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T04:00:38.169582Z","caller":"traceutil/trace.go:171","msg":"trace[283844927] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2746; }","duration":"198.566081ms","start":"2026-08-26T04:00:37.970971Z","end":"2026-08-26T04:00:38.169537Z","steps":["trace[283844927] 'agreement among raft nodes before linearized reading' (duration: 198.295376ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:00:38.168951Z","caller":"traceutil/trace.go:171","msg":"trace[2144394704] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"268.168198ms","start":"2026-08-26T04:00:37.900745Z","end":"2026-08-26T04:00:38.168914Z","steps":["trace[2144394704] 'process raft request' (duration: 268.080137ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:17.721777Z","caller":"traceutil/trace.go:171","msg":"trace[1916474938] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"114.981269ms","start":"2026-08-26T04:04:17.606777Z","end":"2026-08-26T04:04:17.721759Z","steps":["trace[1916474938] 'process raft request' (duration: 114.723644ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:18.573739Z","caller":"traceutil/trace.go:171","msg":"trace[523791634] linearizableReadLoop","detail":"{readStateIndex:4702; appliedIndex:4700; }","duration":"115.040086ms","start":"2026-08-26T04:04:18.458678Z","end":"2026-08-26T04:04:18.573718Z","steps":["trace[523791634] 'read index received' (duration: 47.611836ms)","trace[523791634] 'applied index is now lower than readState.Index' (duration: 67.42719ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T04:04:18.574363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.659468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T04:04:18.574413Z","caller":"traceutil/trace.go:171","msg":"trace[1525593347] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:4534; }","duration":"115.762291ms","start":"2026-08-26T04:04:18.458636Z","end":"2026-08-26T04:04:18.574398Z","steps":["trace[1525593347] 'agreement among raft nodes before linearized reading' (duration: 115.173629ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:18.574386Z","caller":"traceutil/trace.go:171","msg":"trace[1760520705] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"115.915984ms","start":"2026-08-26T04:04:18.458428Z","end":"2026-08-26T04:04:18.574344Z","steps":["trace[1760520705] 'process raft request' (duration: 115.187939ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:21.92328Z","caller":"traceutil/trace.go:171","msg":"trace[333484298] linearizableReadLoop","detail":"{readStateIndex:4791; appliedIndex:4790; }","duration":"102.578035ms","start":"2026-08-26T04:04:21.820682Z","end":"2026-08-26T04:04:21.92326Z","steps":["trace[333484298] 'read index received' (duration: 102.420092ms)","trace[333484298] 'applied index is now lower than readState.Index' (duration: 156.913µs)"],"step_count":2} {"level":"warn","ts":"2026-08-26T04:04:21.923485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.790038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-26T04:04:21.923514Z","caller":"traceutil/trace.go:171","msg":"trace[108287219] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4619; }","duration":"102.88502ms","start":"2026-08-26T04:04:21.820619Z","end":"2026-08-26T04:04:21.923504Z","steps":["trace[108287219] 'agreement among raft nodes before linearized reading' (duration: 102.714977ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:21.923731Z","caller":"traceutil/trace.go:171","msg":"trace[119188312] transaction","detail":"{read_only:false; response_revision:4619; number_of_response:1; }","duration":"126.534388ms","start":"2026-08-26T04:04:21.79719Z","end":"2026-08-26T04:04:21.923724Z","steps":["trace[119188312] 'process raft request' (duration: 125.937596ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:27.303995Z","caller":"traceutil/trace.go:171","msg":"trace[857405051] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"164.294944ms","start":"2026-08-26T04:04:27.139662Z","end":"2026-08-26T04:04:27.303957Z","steps":["trace[857405051] 'process raft request' (duration: 105.875355ms)","trace[857405051] 'compare' (duration: 58.145574ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T04:04:27.304377Z","caller":"traceutil/trace.go:171","msg":"trace[1149166830] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"162.688652ms","start":"2026-08-26T04:04:27.141661Z","end":"2026-08-26T04:04:27.30435Z","steps":["trace[1149166830] 'process raft request' (duration: 162.226563ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:27.894483Z","caller":"traceutil/trace.go:171","msg":"trace[693385824] linearizableReadLoop","detail":"{readStateIndex:4902; appliedIndex:4901; }","duration":"398.884327ms","start":"2026-08-26T04:04:27.495577Z","end":"2026-08-26T04:04:27.894461Z","steps":["trace[693385824] 'read index received' (duration: 398.720374ms)","trace[693385824] 'applied index is now lower than readState.Index' (duration: 162.953µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T04:04:27.894613Z","caller":"traceutil/trace.go:171","msg":"trace[1048089800] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"420.439967ms","start":"2026-08-26T04:04:27.474159Z","end":"2026-08-26T04:04:27.894599Z","steps":["trace[1048089800] 'process raft request' (duration: 420.156801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:27.894731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:27.474147Z","time spent":"420.496509ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7566,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T04:04:27.895039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.462819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"info","ts":"2026-08-26T04:04:27.895129Z","caller":"traceutil/trace.go:171","msg":"trace[177417831] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4729; }","duration":"399.593592ms","start":"2026-08-26T04:04:27.495513Z","end":"2026-08-26T04:04:27.895106Z","steps":["trace[177417831] 'agreement among raft nodes before linearized reading' (duration: 399.445039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:27.895119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.764485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-107c702c-257c-4527-a6df-13d90be96718\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-08-26T04:04:27.895176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:27.495491Z","time spent":"399.676053ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16253,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-08-26T04:04:27.89521Z","caller":"traceutil/trace.go:171","msg":"trace[1225487652] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-107c702c-257c-4527-a6df-13d90be96718; range_end:; response_count:1; response_revision:4729; }","duration":"259.906348ms","start":"2026-08-26T04:04:27.635279Z","end":"2026-08-26T04:04:27.895185Z","steps":["trace[1225487652] 'agreement among raft nodes before linearized reading' (duration: 259.631113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:27.895119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.268469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-26T04:04:27.895339Z","caller":"traceutil/trace.go:171","msg":"trace[1477110135] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4729; }","duration":"389.494363ms","start":"2026-08-26T04:04:27.50581Z","end":"2026-08-26T04:04:27.895305Z","steps":["trace[1477110135] 'agreement among raft nodes before linearized reading' (duration: 389.126136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:27.895376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.237776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5f7c9d7868-pvnzj.18cf3f3342db7455\" ","response":"range_response_count:1 size:898"} {"level":"warn","ts":"2026-08-26T04:04:27.895403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:27.505803Z","time spent":"389.581366ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-08-26T04:04:27.895423Z","caller":"traceutil/trace.go:171","msg":"trace[360610266] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5f7c9d7868-pvnzj.18cf3f3342db7455; range_end:; response_count:1; response_revision:4729; }","duration":"396.757895ms","start":"2026-08-26T04:04:27.498652Z","end":"2026-08-26T04:04:27.89541Z","steps":["trace[360610266] 'agreement among raft nodes before linearized reading' (duration: 396.151694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:27.895457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:27.498638Z","time spent":"396.810707ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":920,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5f7c9d7868-pvnzj.18cf3f3342db7455\" "} {"level":"info","ts":"2026-08-26T04:04:41.653045Z","caller":"traceutil/trace.go:171","msg":"trace[1402544057] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"199.297991ms","start":"2026-08-26T04:04:41.453727Z","end":"2026-08-26T04:04:41.653025Z","steps":["trace[1402544057] 'process raft request' (duration: 199.174278ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:04:41.983092Z","caller":"traceutil/trace.go:171","msg":"trace[423388994] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"488.408265ms","start":"2026-08-26T04:04:41.494657Z","end":"2026-08-26T04:04:41.983065Z","steps":["trace[423388994] 'process raft request' (duration: 448.274654ms)","trace[423388994] 'compare' (duration: 39.782304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T04:04:41.983215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:41.494634Z","time spent":"488.514167ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T04:04:41.983218Z","caller":"traceutil/trace.go:171","msg":"trace[1866173393] linearizableReadLoop","detail":"{readStateIndex:5080; appliedIndex:5079; }","duration":"488.442346ms","start":"2026-08-26T04:04:41.494752Z","end":"2026-08-26T04:04:41.983195Z","steps":["trace[1866173393] 'read index received' (duration: 158.560678ms)","trace[1866173393] 'applied index is now lower than readState.Index' (duration: 329.878768ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T04:04:41.983442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.522043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T04:04:41.983472Z","caller":"traceutil/trace.go:171","msg":"trace[68800837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4902; }","duration":"482.686666ms","start":"2026-08-26T04:04:41.500777Z","end":"2026-08-26T04:04:41.983463Z","steps":["trace[68800837] 'agreement among raft nodes before linearized reading' (duration: 482.544894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:41.983498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:41.500744Z","time spent":"482.748058ms","remote":"127.0.0.1:45504","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-08-26T04:04:41.983502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.486541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16550"} {"level":"warn","ts":"2026-08-26T04:04:41.983467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.332432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-26T04:04:41.983535Z","caller":"traceutil/trace.go:171","msg":"trace[1693429053] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4902; }","duration":"319.542152ms","start":"2026-08-26T04:04:41.663982Z","end":"2026-08-26T04:04:41.983524Z","steps":["trace[1693429053] 'agreement among raft nodes before linearized reading' (duration: 319.41216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:41.98357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:41.663967Z","time spent":"319.597243ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16572,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-26T04:04:41.983645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.978013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-08-26T04:04:41.983678Z","caller":"traceutil/trace.go:171","msg":"trace[1377483132] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4902; }","duration":"376.049244ms","start":"2026-08-26T04:04:41.607621Z","end":"2026-08-26T04:04:41.98367Z","steps":["trace[1377483132] 'agreement among raft nodes before linearized reading' (duration: 375.962383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:41.983699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:41.607601Z","time spent":"376.090585ms","remote":"127.0.0.1:56454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3338,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-08-26T04:04:41.98388Z","caller":"traceutil/trace.go:171","msg":"trace[2141233852] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4902; }","duration":"305.477095ms","start":"2026-08-26T04:04:41.678083Z","end":"2026-08-26T04:04:41.983561Z","steps":["trace[2141233852] 'agreement among raft nodes before linearized reading' (duration: 305.250761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:41.983962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:41.678053Z","time spent":"305.893942ms","remote":"127.0.0.1:45396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-26T04:04:41.984405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.648399ms","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-08-26T04:04:41.984466Z","caller":"traceutil/trace.go:171","msg":"trace[1778758861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4902; }","duration":"489.706889ms","start":"2026-08-26T04:04:41.494726Z","end":"2026-08-26T04:04:41.984433Z","steps":["trace[1778758861] 'agreement among raft nodes before linearized reading' (duration: 488.539208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:41.984505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:04:41.494717Z","time spent":"489.77584ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-26T04:04:42.103484Z","caller":"traceutil/trace.go:171","msg":"trace[1582251133] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"100.87096ms","start":"2026-08-26T04:04:42.002583Z","end":"2026-08-26T04:04:42.103454Z","steps":["trace[1582251133] 'process raft request' (duration: 95.352386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:04:42.103734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.270438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-26T04:04:42.103822Z","caller":"traceutil/trace.go:171","msg":"trace[2005717376] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4906; }","duration":"110.39623ms","start":"2026-08-26T04:04:41.993407Z","end":"2026-08-26T04:04:42.103803Z","steps":["trace[2005717376] 'agreement among raft nodes before linearized reading' (duration: 110.108364ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:05:32.711583Z","caller":"traceutil/trace.go:171","msg":"trace[151538527] linearizableReadLoop","detail":"{readStateIndex:5500; appliedIndex:5499; }","duration":"279.26116ms","start":"2026-08-26T04:05:32.432293Z","end":"2026-08-26T04:05:32.711554Z","steps":["trace[151538527] 'read index received' (duration: 278.972095ms)","trace[151538527] 'applied index is now lower than readState.Index' (duration: 287.965µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T04:05:32.71165Z","caller":"traceutil/trace.go:171","msg":"trace[474103132] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"347.831159ms","start":"2026-08-26T04:05:32.363772Z","end":"2026-08-26T04:05:32.711603Z","steps":["trace[474103132] 'process raft request' (duration: 347.543424ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:05:32.711756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.439714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T04:05:32.711801Z","caller":"traceutil/trace.go:171","msg":"trace[1598003229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5310; }","duration":"279.540476ms","start":"2026-08-26T04:05:32.432246Z","end":"2026-08-26T04:05:32.711787Z","steps":["trace[1598003229] 'agreement among raft nodes before linearized reading' (duration: 279.455595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T04:05:32.711894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T04:05:32.363757Z","time spent":"347.983733ms","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T04:05:32.713156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.152585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-08-26T04:05:32.713288Z","caller":"traceutil/trace.go:171","msg":"trace[1149246906] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5310; }","duration":"205.268788ms","start":"2026-08-26T04:05:32.507951Z","end":"2026-08-26T04:05:32.71322Z","steps":["trace[1149246906] 'agreement among raft nodes before linearized reading' (duration: 203.840043ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:06:09.981151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2911} {"level":"info","ts":"2026-08-26T04:06:10.080854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2911,"took":"96.633653ms","hash":1838620373,"current-db-size-bytes":53448704,"current-db-size":"53 MB","current-db-size-in-use-bytes":28971008,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-26T04:06:10.080913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1838620373,"revision":2911,"compact-revision":-1} {"level":"info","ts":"2026-08-26T04:08:24.294404Z","caller":"traceutil/trace.go:171","msg":"trace[788448520] transaction","detail":"{read_only:false; response_revision:6520; number_of_response:1; }","duration":"136.478237ms","start":"2026-08-26T04:08:24.157899Z","end":"2026-08-26T04:08:24.294378Z","steps":["trace[788448520] 'process raft request' (duration: 136.328645ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:08:47.830235Z","caller":"traceutil/trace.go:171","msg":"trace[1308750404] transaction","detail":"{read_only:false; response_revision:6695; number_of_response:1; }","duration":"137.964443ms","start":"2026-08-26T04:08:47.692252Z","end":"2026-08-26T04:08:47.830216Z","steps":["trace[1308750404] 'process raft request' (duration: 137.019449ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T04:11:09.989312Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5588} {"level":"info","ts":"2026-08-26T04:11:10.071666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5588,"took":"79.99431ms","hash":53058576,"current-db-size-bytes":53448704,"current-db-size":"53 MB","current-db-size-in-use-bytes":18210816,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-26T04:11:10.071737Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":53058576,"revision":5588,"compact-revision":2911}