{"level":"warn","ts":"2026-09-18T23:03:50.83242Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T23:03:50.832514Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.220: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.220:2380","--initial-cluster=instance=https://162.253.55.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.220: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-09-18T23:03:50.83258Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T23:03:50.832591Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-18T23:03:50.832627Z","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-09-18T23:03:50.83327Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"]} {"level":"info","ts":"2026-09-18T23:03:50.833346Z","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.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.220: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-09-18T23:03:50.847672Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.081611ms"} {"level":"info","ts":"2026-09-18T23:03:50.857079Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c62f064ba268721","cluster-id":"65b58f2bbdfe5647"} {"level":"info","ts":"2026-09-18T23:03:50.857195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T23:03:50.857222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 0"} {"level":"info","ts":"2026-09-18T23:03:50.85724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c62f064ba268721 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T23:03:50.857248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 1"} {"level":"info","ts":"2026-09-18T23:03:50.857302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"warn","ts":"2026-09-18T23:03:50.863473Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T23:03:50.865386Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T23:03:50.866968Z","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-09-18T23:03:50.869671Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c62f064ba268721","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T23:03:50.869933Z","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-09-18T23:03:50.870019Z","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-09-18T23:03:50.870033Z","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-09-18T23:03:50.86987Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c62f064ba268721","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T23:03:50.875401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"info","ts":"2026-09-18T23:03:50.87581Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","added-peer-id":"4c62f064ba268721","added-peer-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-18T23:03:50.876882Z","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-09-18T23:03:50.877016Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c62f064ba268721","initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T23:03:50.877034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T23:03:50.877101Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-18T23:03:50.877109Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-18T23:03:51.15781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T23:03:51.157855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T23:03:51.157883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgPreVoteResp from 4c62f064ba268721 at term 1"} {"level":"info","ts":"2026-09-18T23:03:51.157896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became candidate at term 2"} {"level":"info","ts":"2026-09-18T23:03:51.157907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgVoteResp from 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-18T23:03:51.157917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became leader at term 2"} {"level":"info","ts":"2026-09-18T23:03:51.157929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c62f064ba268721 elected leader 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-18T23:03:51.159822Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T23:03:51.160848Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c62f064ba268721","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.220:2379]}","request-path":"/0/members/4c62f064ba268721/attributes","cluster-id":"65b58f2bbdfe5647","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T23:03:51.161073Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T23:03:51.161141Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T23:03:51.161195Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T23:03:51.16126Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T23:03:51.161395Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T23:03:51.161435Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T23:03:51.161225Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T23:03:51.163674Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T23:03:51.16613Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.220:2379"} {"level":"warn","ts":"2026-09-18T23:03:55.61062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.21496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:03:55.610739Z","caller":"traceutil/trace.go:171","msg":"trace[569571408] linearizableReadLoop","detail":"{readStateIndex:206; appliedIndex:205; }","duration":"240.180412ms","start":"2026-09-18T23:03:55.370542Z","end":"2026-09-18T23:03:55.610722Z","steps":["trace[569571408] 'read index received' (duration: 66.845µs)","trace[569571408] 'applied index is now lower than readState.Index' (duration: 240.112197ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:03:55.610829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.287198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:03:55.610856Z","caller":"traceutil/trace.go:171","msg":"trace[849323679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:201; }","duration":"240.358731ms","start":"2026-09-18T23:03:55.370484Z","end":"2026-09-18T23:03:55.610843Z","steps":["trace[849323679] 'agreement among raft nodes before linearized reading' (duration: 240.288158ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:03:55.610817Z","caller":"traceutil/trace.go:171","msg":"trace[974175820] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"681.94123ms","start":"2026-09-18T23:03:54.928825Z","end":"2026-09-18T23:03:55.610766Z","steps":["trace[974175820] 'process raft request' (duration: 322.066705ms)","trace[974175820] 'compare' (duration: 359.067311ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:03:55.610994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:03:54.928798Z","time spent":"682.105599ms","remote":"127.0.0.1:42518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T23:03:55.79048Z","caller":"traceutil/trace.go:171","msg":"trace[1978499025] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"175.454066ms","start":"2026-09-18T23:03:55.614994Z","end":"2026-09-18T23:03:55.790448Z","steps":["trace[1978499025] 'process raft request' (duration: 172.970963ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:03:55.790467Z","caller":"traceutil/trace.go:171","msg":"trace[469312603] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"174.943627ms","start":"2026-09-18T23:03:55.615487Z","end":"2026-09-18T23:03:55.790431Z","steps":["trace[469312603] 'process raft request' (duration: 174.896815ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:29.481586Z","caller":"traceutil/trace.go:171","msg":"trace[1674416963] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"115.021671ms","start":"2026-09-18T23:04:29.366535Z","end":"2026-09-18T23:04:29.481557Z","steps":["trace[1674416963] 'process raft request' (duration: 109.782231ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:29.482873Z","caller":"traceutil/trace.go:171","msg":"trace[783742896] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"115.92541ms","start":"2026-09-18T23:04:29.366931Z","end":"2026-09-18T23:04:29.482856Z","steps":["trace[783742896] 'process raft request' (duration: 115.66087ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:29.487253Z","caller":"traceutil/trace.go:171","msg":"trace[703500764] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"108.091953ms","start":"2026-09-18T23:04:29.379117Z","end":"2026-09-18T23:04:29.487209Z","steps":["trace[703500764] 'process raft request' (duration: 104.930139ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:29.48744Z","caller":"traceutil/trace.go:171","msg":"trace[546365620] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"112.160987ms","start":"2026-09-18T23:04:29.375247Z","end":"2026-09-18T23:04:29.487408Z","steps":["trace[546365620] 'process raft request' (duration: 107.453675ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:31.103007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.908426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T23:04:31.103029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.061486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:04:31.103086Z","caller":"traceutil/trace.go:171","msg":"trace[125424486] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:563; }","duration":"101.031996ms","start":"2026-09-18T23:04:31.002026Z","end":"2026-09-18T23:04:31.103058Z","steps":["trace[125424486] 'range keys from in-memory index tree' (duration: 100.828556ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:31.103124Z","caller":"traceutil/trace.go:171","msg":"trace[707547445] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:563; }","duration":"101.173786ms","start":"2026-09-18T23:04:31.001918Z","end":"2026-09-18T23:04:31.103092Z","steps":["trace[707547445] 'count revisions from in-memory index tree' (duration: 100.934126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:31.10287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.439237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:04:31.103697Z","caller":"traceutil/trace.go:171","msg":"trace[536543129] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:563; }","duration":"101.369867ms","start":"2026-09-18T23:04:31.002311Z","end":"2026-09-18T23:04:31.103681Z","steps":["trace[536543129] 'range keys from in-memory index tree' (duration: 100.315377ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:35.816474Z","caller":"traceutil/trace.go:171","msg":"trace[318663114] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"291.158857ms","start":"2026-09-18T23:04:35.525288Z","end":"2026-09-18T23:04:35.816447Z","steps":["trace[318663114] 'read index received' (duration: 289.222271ms)","trace[318663114] 'applied index is now lower than readState.Index' (duration: 1.935626ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:35.816662Z","caller":"traceutil/trace.go:171","msg":"trace[1956299754] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"306.326484ms","start":"2026-09-18T23:04:35.510324Z","end":"2026-09-18T23:04:35.816651Z","steps":["trace[1956299754] 'process raft request' (duration: 305.16764ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:35.817033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.6891ms","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-09-18T23:04:35.81716Z","caller":"traceutil/trace.go:171","msg":"trace[1195922642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"291.869391ms","start":"2026-09-18T23:04:35.525257Z","end":"2026-09-18T23:04:35.817126Z","steps":["trace[1195922642] 'agreement among raft nodes before linearized reading' (duration: 291.459319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:35.817112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:35.510299Z","time spent":"306.439594ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:04:36.298709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.23777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:04:36.299215Z","caller":"traceutil/trace.go:171","msg":"trace[1464009749] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"401.656327ms","start":"2026-09-18T23:04:35.897528Z","end":"2026-09-18T23:04:36.299184Z","steps":["trace[1464009749] 'process raft request' (duration: 401.588477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:36.299429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:35.897492Z","time spent":"401.841208ms","remote":"127.0.0.1:42618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T23:04:36.299759Z","caller":"traceutil/trace.go:171","msg":"trace[738928776] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"482.925257ms","start":"2026-09-18T23:04:35.816609Z","end":"2026-09-18T23:04:36.299534Z","steps":["trace[738928776] 'read index received' (duration: 33.618523ms)","trace[738928776] 'applied index is now lower than readState.Index' (duration: 449.304214ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:36.299778Z","caller":"traceutil/trace.go:171","msg":"trace[102013797] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"477.517841ms","start":"2026-09-18T23:04:35.82203Z","end":"2026-09-18T23:04:36.299548Z","steps":["trace[102013797] 'process raft request' (duration: 476.98752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:36.300043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.876078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:04:36.300045Z","caller":"traceutil/trace.go:171","msg":"trace[431809235] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"772.503481ms","start":"2026-09-18T23:04:35.527496Z","end":"2026-09-18T23:04:36.3Z","steps":["trace[431809235] 'process raft request' (duration: 322.768915ms)","trace[431809235] 'compare' (duration: 448.05721ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:04:36.3001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:35.821981Z","time spent":"478.000663ms","remote":"127.0.0.1:42678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T23:04:36.300125Z","caller":"traceutil/trace.go:171","msg":"trace[1172375008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"625.956079ms","start":"2026-09-18T23:04:35.674145Z","end":"2026-09-18T23:04:36.300102Z","steps":["trace[1172375008] 'agreement among raft nodes before linearized reading' (duration: 625.809708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:36.300173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:35.67409Z","time spent":"626.07365ms","remote":"127.0.0.1:42426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T23:04:36.3002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:04:35.527469Z","time spent":"772.650662ms","remote":"127.0.0.1:42602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:04:36.300418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.237485ms","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-09-18T23:04:36.30048Z","caller":"traceutil/trace.go:171","msg":"trace[1873173992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"276.455776ms","start":"2026-09-18T23:04:36.024008Z","end":"2026-09-18T23:04:36.300464Z","steps":["trace[1873173992] 'agreement among raft nodes before linearized reading' (duration: 276.137285ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:04:36.442472Z","caller":"traceutil/trace.go:171","msg":"trace[1432841180] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"123.941177ms","start":"2026-09-18T23:04:36.318502Z","end":"2026-09-18T23:04:36.442443Z","steps":["trace[1432841180] 'read index received' (duration: 111.67512ms)","trace[1432841180] 'applied index is now lower than readState.Index' (duration: 12.264847ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:04:36.442676Z","caller":"traceutil/trace.go:171","msg":"trace[1034612260] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"135.241502ms","start":"2026-09-18T23:04:36.30739Z","end":"2026-09-18T23:04:36.442632Z","steps":["trace[1034612260] 'process raft request' (duration: 122.795323ms)","trace[1034612260] 'compare' (duration: 12.006976ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:04:36.442928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.49825ms","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-09-18T23:04:36.443015Z","caller":"traceutil/trace.go:171","msg":"trace[1061909717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"119.620092ms","start":"2026-09-18T23:04:36.323374Z","end":"2026-09-18T23:04:36.442994Z","steps":["trace[1061909717] 'agreement among raft nodes before linearized reading' (duration: 119.351171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:04:36.443096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.57536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:04:36.44314Z","caller":"traceutil/trace.go:171","msg":"trace[1797479482] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:588; }","duration":"124.67078ms","start":"2026-09-18T23:04:36.318453Z","end":"2026-09-18T23:04:36.443124Z","steps":["trace[1797479482] 'agreement among raft nodes before linearized reading' (duration: 124.59176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:05:22.841112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.231675ms","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-09-18T23:05:22.841211Z","caller":"traceutil/trace.go:171","msg":"trace[872378233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"152.386199ms","start":"2026-09-18T23:05:22.688802Z","end":"2026-09-18T23:05:22.841188Z","steps":["trace[872378233] 'range keys from in-memory index tree' (duration: 152.021299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:05:22.841387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.936679ms","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-09-18T23:05:22.841489Z","caller":"traceutil/trace.go:171","msg":"trace[1285101262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1158; }","duration":"169.049192ms","start":"2026-09-18T23:05:22.672417Z","end":"2026-09-18T23:05:22.841466Z","steps":["trace[1285101262] 'range keys from in-memory index tree' (duration: 168.695212ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:12.163035Z","caller":"traceutil/trace.go:171","msg":"trace[191038436] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"154.993847ms","start":"2026-09-18T23:06:12.008009Z","end":"2026-09-18T23:06:12.163003Z","steps":["trace[191038436] 'process raft request' (duration: 154.808879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:06:12.498527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.250919ms","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-09-18T23:06:12.498657Z","caller":"traceutil/trace.go:171","msg":"trace[347947040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1835; }","duration":"128.432827ms","start":"2026-09-18T23:06:12.370198Z","end":"2026-09-18T23:06:12.498631Z","steps":["trace[347947040] 'range keys from in-memory index tree' (duration: 128.02995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:06:12.914663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.321727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:06:12.914757Z","caller":"traceutil/trace.go:171","msg":"trace[420602768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1836; }","duration":"287.470323ms","start":"2026-09-18T23:06:12.627266Z","end":"2026-09-18T23:06:12.914737Z","steps":["trace[420602768] 'range keys from in-memory index tree' (duration: 287.079077ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:26.157696Z","caller":"traceutil/trace.go:171","msg":"trace[1703527811] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"315.939142ms","start":"2026-09-18T23:06:25.841707Z","end":"2026-09-18T23:06:26.157646Z","steps":["trace[1703527811] 'process raft request' (duration: 315.770373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:06:26.157848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:06:25.841662Z","time spent":"316.111829ms","remote":"127.0.0.1:42678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:06:41.267704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.134915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:06:41.267825Z","caller":"traceutil/trace.go:171","msg":"trace[993260349] linearizableReadLoop","detail":"{readStateIndex:2126; appliedIndex:2125; }","duration":"145.129105ms","start":"2026-09-18T23:06:41.122681Z","end":"2026-09-18T23:06:41.26781Z","steps":["trace[993260349] 'read index received' (duration: 83.294µs)","trace[993260349] 'applied index is now lower than readState.Index' (duration: 145.044641ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:06:41.267987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.298003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-18T23:06:41.268059Z","caller":"traceutil/trace.go:171","msg":"trace[2010170776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2053; }","duration":"145.413729ms","start":"2026-09-18T23:06:41.122628Z","end":"2026-09-18T23:06:41.268041Z","steps":["trace[2010170776] 'agreement among raft nodes before linearized reading' (duration: 145.256981ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:41.26855Z","caller":"traceutil/trace.go:171","msg":"trace[222319350] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"267.344663ms","start":"2026-09-18T23:06:41.000505Z","end":"2026-09-18T23:06:41.267849Z","steps":["trace[222319350] 'process raft request' (duration: 53.967107ms)","trace[222319350] 'compare' (duration: 213.02167ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:06:47.613833Z","caller":"traceutil/trace.go:171","msg":"trace[578090370] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"232.132376ms","start":"2026-09-18T23:06:47.381663Z","end":"2026-09-18T23:06:47.613795Z","steps":["trace[578090370] 'process raft request' (duration: 232.015819ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:47.620374Z","caller":"traceutil/trace.go:171","msg":"trace[1370391889] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"231.836263ms","start":"2026-09-18T23:06:47.388513Z","end":"2026-09-18T23:06:47.620349Z","steps":["trace[1370391889] 'process raft request' (duration: 231.657384ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:47.620461Z","caller":"traceutil/trace.go:171","msg":"trace[256933600] linearizableReadLoop","detail":"{readStateIndex:2162; appliedIndex:2160; }","duration":"175.89455ms","start":"2026-09-18T23:06:47.444547Z","end":"2026-09-18T23:06:47.620442Z","steps":["trace[256933600] 'read index received' (duration: 169.202381ms)","trace[256933600] 'applied index is now lower than readState.Index' (duration: 6.690819ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:06:47.620552Z","caller":"traceutil/trace.go:171","msg":"trace[1326226870] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"164.910361ms","start":"2026-09-18T23:06:47.455588Z","end":"2026-09-18T23:06:47.620499Z","steps":["trace[1326226870] 'process raft request' (duration: 164.711092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:06:47.620804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.240987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-18T23:06:47.620812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.875159ms","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-09-18T23:06:47.620854Z","caller":"traceutil/trace.go:171","msg":"trace[1487677392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2089; }","duration":"176.346291ms","start":"2026-09-18T23:06:47.444496Z","end":"2026-09-18T23:06:47.620843Z","steps":["trace[1487677392] 'agreement among raft nodes before linearized reading' (duration: 176.10445ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:47.620922Z","caller":"traceutil/trace.go:171","msg":"trace[219759885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2089; }","duration":"176.045596ms","start":"2026-09-18T23:06:47.444858Z","end":"2026-09-18T23:06:47.620903Z","steps":["trace[219759885] 'agreement among raft nodes before linearized reading' (duration: 175.791925ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:48.115792Z","caller":"traceutil/trace.go:171","msg":"trace[222590502] linearizableReadLoop","detail":"{readStateIndex:2167; appliedIndex:2166; }","duration":"314.637859ms","start":"2026-09-18T23:06:47.801124Z","end":"2026-09-18T23:06:48.115762Z","steps":["trace[222590502] 'read index received' (duration: 216.145017ms)","trace[222590502] 'applied index is now lower than readState.Index' (duration: 98.491392ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:06:48.116019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.876462ms","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-09-18T23:06:48.116014Z","caller":"traceutil/trace.go:171","msg":"trace[1802244404] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"394.675909ms","start":"2026-09-18T23:06:47.721319Z","end":"2026-09-18T23:06:48.115995Z","steps":["trace[1802244404] 'process raft request' (duration: 296.025418ms)","trace[1802244404] 'compare' (duration: 97.99876ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:06:48.11606Z","caller":"traceutil/trace.go:171","msg":"trace[527491899] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2093; }","duration":"314.962735ms","start":"2026-09-18T23:06:47.801084Z","end":"2026-09-18T23:06:48.116047Z","steps":["trace[527491899] 'agreement among raft nodes before linearized reading' (duration: 314.772586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:06:48.116088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:06:47.801065Z","time spent":"315.015378ms","remote":"127.0.0.1:34142","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-09-18T23:06:48.116146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:06:47.721295Z","time spent":"394.765243ms","remote":"127.0.0.1:42678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T23:06:48.486669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.045755ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T23:06:48.486915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.665149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:06:48.486933Z","caller":"traceutil/trace.go:171","msg":"trace[158534531] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2093; }","duration":"159.317798ms","start":"2026-09-18T23:06:48.327591Z","end":"2026-09-18T23:06:48.486909Z","steps":["trace[158534531] 'range keys from in-memory index tree' (duration: 159.029284ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:06:48.487018Z","caller":"traceutil/trace.go:171","msg":"trace[164607001] transaction","detail":"{read_only:false; response_revision:2094; number_of_response:1; }","duration":"260.140238ms","start":"2026-09-18T23:06:48.226854Z","end":"2026-09-18T23:06:48.486994Z","steps":["trace[164607001] 'process raft request' (duration: 93.26952ms)","trace[164607001] 'compare' (duration: 166.372525ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:08:00.20585Z","caller":"traceutil/trace.go:171","msg":"trace[232938792] linearizableReadLoop","detail":"{readStateIndex:2667; appliedIndex:2666; }","duration":"189.50149ms","start":"2026-09-18T23:08:00.016325Z","end":"2026-09-18T23:08:00.205827Z","steps":["trace[232938792] 'read index received' (duration: 189.430046ms)","trace[232938792] 'applied index is now lower than readState.Index' (duration: 70.424µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:08:00.206142Z","caller":"traceutil/trace.go:171","msg":"trace[1267603240] transaction","detail":"{read_only:false; response_revision:2576; number_of_response:1; }","duration":"243.058438ms","start":"2026-09-18T23:07:59.963067Z","end":"2026-09-18T23:08:00.206126Z","steps":["trace[1267603240] 'process raft request' (duration: 242.611395ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:00.206469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.124502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-09-18T23:08:00.206474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.831112ms","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-09-18T23:08:00.206512Z","caller":"traceutil/trace.go:171","msg":"trace[2100176272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2576; }","duration":"190.201206ms","start":"2026-09-18T23:08:00.016299Z","end":"2026-09-18T23:08:00.2065Z","steps":["trace[2100176272] 'agreement among raft nodes before linearized reading' (duration: 190.063319ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:08:00.206546Z","caller":"traceutil/trace.go:171","msg":"trace[1540025175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2576; }","duration":"179.971028ms","start":"2026-09-18T23:08:00.026556Z","end":"2026-09-18T23:08:00.206527Z","steps":["trace[1540025175] 'agreement among raft nodes before linearized reading' (duration: 179.753517ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:08:00.20656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.276301ms","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-09-18T23:08:00.206694Z","caller":"traceutil/trace.go:171","msg":"trace[2144299916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2576; }","duration":"120.440459ms","start":"2026-09-18T23:08:00.086213Z","end":"2026-09-18T23:08:00.206653Z","steps":["trace[2144299916] 'agreement among raft nodes before linearized reading' (duration: 120.182456ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:12:56.368902Z","caller":"traceutil/trace.go:171","msg":"trace[553950193] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"181.46473ms","start":"2026-09-18T23:12:56.187421Z","end":"2026-09-18T23:12:56.368886Z","steps":["trace[553950193] 'process raft request' (duration: 108.658761ms)","trace[553950193] 'compare' (duration: 72.520618ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:12:56.369537Z","caller":"traceutil/trace.go:171","msg":"trace[1291615506] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"138.91236ms","start":"2026-09-18T23:12:56.230618Z","end":"2026-09-18T23:12:56.36953Z","steps":["trace[1291615506] 'process raft request' (duration: 138.17453ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:12:57.881584Z","caller":"traceutil/trace.go:171","msg":"trace[823154000] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4845; }","duration":"117.596724ms","start":"2026-09-18T23:12:57.763737Z","end":"2026-09-18T23:12:57.881334Z","steps":["trace[823154000] 'read index received' (duration: 117.520601ms)","trace[823154000] 'applied index is now lower than readState.Index' (duration: 54.212µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:12:57.881638Z","caller":"traceutil/trace.go:171","msg":"trace[1671219007] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"118.523141ms","start":"2026-09-18T23:12:57.763081Z","end":"2026-09-18T23:12:57.881604Z","steps":["trace[1671219007] 'process raft request' (duration: 118.063562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:12:57.881954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.181498ms","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-09-18T23:12:57.882185Z","caller":"traceutil/trace.go:171","msg":"trace[394826654] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4668; }","duration":"118.474859ms","start":"2026-09-18T23:12:57.763688Z","end":"2026-09-18T23:12:57.882163Z","steps":["trace[394826654] 'agreement among raft nodes before linearized reading' (duration: 117.99896ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:12:57.908304Z","caller":"traceutil/trace.go:171","msg":"trace[1925745398] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"122.300753ms","start":"2026-09-18T23:12:57.78597Z","end":"2026-09-18T23:12:57.908271Z","steps":["trace[1925745398] 'process raft request' (duration: 122.093545ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:12:57.908524Z","caller":"traceutil/trace.go:171","msg":"trace[2091304607] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"113.951269ms","start":"2026-09-18T23:12:57.794553Z","end":"2026-09-18T23:12:57.908505Z","steps":["trace[2091304607] 'process raft request' (duration: 113.654357ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:01.275463Z","caller":"traceutil/trace.go:171","msg":"trace[1979926515] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"239.549548ms","start":"2026-09-18T23:13:01.03587Z","end":"2026-09-18T23:13:01.275419Z","steps":["trace[1979926515] 'process raft request' (duration: 239.354299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T23:13:01.27557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.528358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T23:13:01.275626Z","caller":"traceutil/trace.go:171","msg":"trace[1757469424] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4773; }","duration":"194.635503ms","start":"2026-09-18T23:13:01.080974Z","end":"2026-09-18T23:13:01.27561Z","steps":["trace[1757469424] 'agreement among raft nodes before linearized reading' (duration: 194.522799ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:01.275387Z","caller":"traceutil/trace.go:171","msg":"trace[1364232798] linearizableReadLoop","detail":"{readStateIndex:4952; appliedIndex:4951; }","duration":"194.338582ms","start":"2026-09-18T23:13:01.081026Z","end":"2026-09-18T23:13:01.275364Z","steps":["trace[1364232798] 'read index received' (duration: 194.146443ms)","trace[1364232798] 'applied index is now lower than readState.Index' (duration: 191.238µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:13:01.316275Z","caller":"traceutil/trace.go:171","msg":"trace[1738402144] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"108.049522ms","start":"2026-09-18T23:13:01.208196Z","end":"2026-09-18T23:13:01.316246Z","steps":["trace[1738402144] 'process raft request' (duration: 107.818503ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:01.316303Z","caller":"traceutil/trace.go:171","msg":"trace[414006922] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"107.662277ms","start":"2026-09-18T23:13:01.208613Z","end":"2026-09-18T23:13:01.316276Z","steps":["trace[414006922] 'process raft request' (duration: 107.522442ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:03.460697Z","caller":"traceutil/trace.go:171","msg":"trace[330444826] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"139.221478ms","start":"2026-09-18T23:13:03.321456Z","end":"2026-09-18T23:13:03.460677Z","steps":["trace[330444826] 'process raft request' (duration: 105.426356ms)","trace[330444826] 'compare' (duration: 33.708068ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:13:13.693085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.968249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T23:13:13.693189Z","caller":"traceutil/trace.go:171","msg":"trace[373688764] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"320.818654ms","start":"2026-09-18T23:13:13.372352Z","end":"2026-09-18T23:13:13.69317Z","steps":["trace[373688764] 'process raft request' (duration: 139.687669ms)","trace[373688764] 'compare' (duration: 180.636455ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:13:13.693242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T23:13:13.372327Z","time spent":"320.889387ms","remote":"127.0.0.1:42678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T23:13:13.706871Z","caller":"traceutil/trace.go:171","msg":"trace[323286391] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"197.361646ms","start":"2026-09-18T23:13:13.509483Z","end":"2026-09-18T23:13:13.706845Z","steps":["trace[323286391] 'process raft request' (duration: 196.988592ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:13.719787Z","caller":"traceutil/trace.go:171","msg":"trace[244254593] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"149.087572ms","start":"2026-09-18T23:13:13.570671Z","end":"2026-09-18T23:13:13.719758Z","steps":["trace[244254593] 'process raft request' (duration: 148.853382ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:14.131545Z","caller":"traceutil/trace.go:171","msg":"trace[1328506907] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"101.3356ms","start":"2026-09-18T23:13:14.030185Z","end":"2026-09-18T23:13:14.131521Z","steps":["trace[1328506907] 'process raft request' (duration: 100.955835ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:14.255118Z","caller":"traceutil/trace.go:171","msg":"trace[888850046] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"119.823944ms","start":"2026-09-18T23:13:14.135264Z","end":"2026-09-18T23:13:14.255088Z","steps":["trace[888850046] 'process raft request' (duration: 49.718596ms)","trace[888850046] 'compare' (duration: 69.951812ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:13:14.337109Z","caller":"traceutil/trace.go:171","msg":"trace[483771238] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"200.252972ms","start":"2026-09-18T23:13:14.136799Z","end":"2026-09-18T23:13:14.337052Z","steps":["trace[483771238] 'process raft request' (duration: 199.992292ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:14.337169Z","caller":"traceutil/trace.go:171","msg":"trace[1251280965] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"196.719718ms","start":"2026-09-18T23:13:14.140423Z","end":"2026-09-18T23:13:14.337143Z","steps":["trace[1251280965] 'process raft request' (duration: 196.613554ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:14.337517Z","caller":"traceutil/trace.go:171","msg":"trace[1910272400] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"198.771502ms","start":"2026-09-18T23:13:14.138682Z","end":"2026-09-18T23:13:14.337453Z","steps":["trace[1910272400] 'process raft request' (duration: 198.317293ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:14.337548Z","caller":"traceutil/trace.go:171","msg":"trace[1217770275] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"200.645738ms","start":"2026-09-18T23:13:14.136883Z","end":"2026-09-18T23:13:14.337529Z","steps":["trace[1217770275] 'process raft request' (duration: 200.029553ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:14.337735Z","caller":"traceutil/trace.go:171","msg":"trace[2119522636] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"200.772664ms","start":"2026-09-18T23:13:14.136945Z","end":"2026-09-18T23:13:14.337718Z","steps":["trace[2119522636] 'process raft request' (duration: 200.012873ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:27.950214Z","caller":"traceutil/trace.go:171","msg":"trace[653346935] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"184.980656ms","start":"2026-09-18T23:13:27.765201Z","end":"2026-09-18T23:13:27.950182Z","steps":["trace[653346935] 'process raft request' (duration: 158.521964ms)","trace[653346935] 'compare' (duration: 26.109818ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:13:27.950416Z","caller":"traceutil/trace.go:171","msg":"trace[1954648808] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"178.344082ms","start":"2026-09-18T23:13:27.772045Z","end":"2026-09-18T23:13:27.950389Z","steps":["trace[1954648808] 'process raft request' (duration: 177.981156ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:28.344917Z","caller":"traceutil/trace.go:171","msg":"trace[665762303] transaction","detail":"{read_only:false; response_revision:5188; number_of_response:1; }","duration":"103.702265ms","start":"2026-09-18T23:13:28.241138Z","end":"2026-09-18T23:13:28.34484Z","steps":["trace[665762303] 'process raft request' (duration: 103.437704ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:28.519519Z","caller":"traceutil/trace.go:171","msg":"trace[2093055373] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"122.260362ms","start":"2026-09-18T23:13:28.397233Z","end":"2026-09-18T23:13:28.519494Z","steps":["trace[2093055373] 'process raft request' (duration: 122.167878ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:28.51956Z","caller":"traceutil/trace.go:171","msg":"trace[2028946214] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"123.042294ms","start":"2026-09-18T23:13:28.396469Z","end":"2026-09-18T23:13:28.519511Z","steps":["trace[2028946214] 'process raft request' (duration: 88.663184ms)","trace[2028946214] 'compare' (duration: 34.12542ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T23:13:28.519849Z","caller":"traceutil/trace.go:171","msg":"trace[832217107] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"122.390867ms","start":"2026-09-18T23:13:28.39743Z","end":"2026-09-18T23:13:28.51982Z","steps":["trace[832217107] 'process raft request' (duration: 122.023981ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:28.520011Z","caller":"traceutil/trace.go:171","msg":"trace[692048919] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"113.712239ms","start":"2026-09-18T23:13:28.406289Z","end":"2026-09-18T23:13:28.520001Z","steps":["trace[692048919] 'process raft request' (duration: 113.355684ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:28.520089Z","caller":"traceutil/trace.go:171","msg":"trace[1341873405] linearizableReadLoop","detail":"{readStateIndex:5386; appliedIndex:5381; }","duration":"104.664414ms","start":"2026-09-18T23:13:28.415406Z","end":"2026-09-18T23:13:28.52007Z","steps":["trace[1341873405] 'read index received' (duration: 3.984444ms)","trace[1341873405] 'applied index is now lower than readState.Index' (duration: 100.67591ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T23:13:28.520423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.008638ms","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-09-18T23:13:28.520509Z","caller":"traceutil/trace.go:171","msg":"trace[1765431606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5195; }","duration":"105.131943ms","start":"2026-09-18T23:13:28.415362Z","end":"2026-09-18T23:13:28.520494Z","steps":["trace[1765431606] 'agreement among raft nodes before linearized reading' (duration: 104.851192ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:13:51.26518Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-09-18T23:13:51.3571Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"90.820453ms","hash":1883428880,"current-db-size-bytes":51900416,"current-db-size":"52 MB","current-db-size-in-use-bytes":27656192,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T23:13:51.357155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1883428880,"revision":2855,"compact-revision":-1} {"level":"warn","ts":"2026-09-18T23:17:36.13096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.858919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-09-18T23:17:36.131007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.45403ms","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-09-18T23:17:36.131031Z","caller":"traceutil/trace.go:171","msg":"trace[218776757] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6983; }","duration":"151.983278ms","start":"2026-09-18T23:17:35.979033Z","end":"2026-09-18T23:17:36.131017Z","steps":["trace[218776757] 'range keys from in-memory index tree' (duration: 151.676909ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:17:36.131064Z","caller":"traceutil/trace.go:171","msg":"trace[408454954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6983; }","duration":"122.585429ms","start":"2026-09-18T23:17:36.008466Z","end":"2026-09-18T23:17:36.131051Z","steps":["trace[408454954] 'range keys from in-memory index tree' (duration: 122.16692ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T23:18:51.273384Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5343} {"level":"info","ts":"2026-09-18T23:18:51.39815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5343,"took":"123.668959ms","hash":2442447946,"current-db-size-bytes":51900416,"current-db-size":"52 MB","current-db-size-in-use-bytes":18706432,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T23:18:51.39822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2442447946,"revision":5343,"compact-revision":2855}