{"level":"warn","ts":"2026-07-16T06:48:45.44559Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:48:45.445753Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222: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://199.19.213.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222: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-07-16T06:48:45.445949Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T06:48:45.446002Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-07-16T06:48:45.44603Z","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-07-16T06:48:45.446823Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-07-16T06:48:45.446941Z","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://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222: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-07-16T06:48:45.452499Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.214888ms"} {"level":"info","ts":"2026-07-16T06:48:45.461018Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-07-16T06:48:45.461126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T06:48:45.461224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-07-16T06:48:45.461267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T06:48:45.461284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-07-16T06:48:45.461363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-07-16T06:48:45.468956Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T06:48:45.470671Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T06:48:45.471893Z","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-07-16T06:48:45.473942Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T06:48:45.474242Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T06:48:45.474373Z","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-07-16T06:48:45.47444Z","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-07-16T06:48:45.475018Z","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-07-16T06:48:45.475575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-07-16T06:48:45.476468Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-07-16T06:48:45.481121Z","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-07-16T06:48:45.48138Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T06:48:45.481418Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T06:48:45.481402Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-07-16T06:48:45.481523Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-07-16T06:48:45.762093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T06:48:45.762193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T06:48:45.762222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-07-16T06:48:45.762245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-07-16T06:48:45.762257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-07-16T06:48:45.762275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-07-16T06:48:45.76229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-07-16T06:48:45.763304Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:45.763523Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T06:48:45.763779Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:48:45.765067Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:45.76519Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:45.765231Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T06:48:45.765496Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T06:48:45.767309Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T06:48:45.768518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-07-16T06:48:45.774738Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T06:48:45.775044Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-07-16T06:48:47.600735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.307037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-16T06:48:47.601016Z","caller":"traceutil/trace.go:171","msg":"trace[627763401] linearizableReadLoop","detail":"{readStateIndex:53; appliedIndex:51; }","duration":"134.090306ms","start":"2026-07-16T06:48:47.466909Z","end":"2026-07-16T06:48:47.600999Z","steps":["trace[627763401] 'read index received' (duration: 32.008679ms)","trace[627763401] 'applied index is now lower than readState.Index' (duration: 102.080387ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:47.601111Z","caller":"traceutil/trace.go:171","msg":"trace[637380794] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"218.746161ms","start":"2026-07-16T06:48:47.382315Z","end":"2026-07-16T06:48:47.601061Z","steps":["trace[637380794] 'process raft request' (duration: 218.619338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:47.601254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.288352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T06:48:47.601347Z","caller":"traceutil/trace.go:171","msg":"trace[35764295] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:49; }","duration":"134.479267ms","start":"2026-07-16T06:48:47.466849Z","end":"2026-07-16T06:48:47.601328Z","steps":["trace[35764295] 'agreement among raft nodes before linearized reading' (duration: 134.276251ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:47.601382Z","caller":"traceutil/trace.go:171","msg":"trace[471199796] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"218.806502ms","start":"2026-07-16T06:48:47.382273Z","end":"2026-07-16T06:48:47.601079Z","steps":["trace[471199796] 'process raft request' (duration: 116.702974ms)","trace[471199796] 'compare' (duration: 101.153123ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:47.711767Z","caller":"traceutil/trace.go:171","msg":"trace[587989414] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"107.100476ms","start":"2026-07-16T06:48:47.604639Z","end":"2026-07-16T06:48:47.71174Z","steps":["trace[587989414] 'process raft request' (duration: 107.017344ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:47.711779Z","caller":"traceutil/trace.go:171","msg":"trace[1709797413] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"108.096261ms","start":"2026-07-16T06:48:47.603649Z","end":"2026-07-16T06:48:47.711746Z","steps":["trace[1709797413] 'process raft request' (duration: 105.828273ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:47.711883Z","caller":"traceutil/trace.go:171","msg":"trace[479526973] linearizableReadLoop","detail":"{readStateIndex:54; appliedIndex:53; }","duration":"107.843385ms","start":"2026-07-16T06:48:47.604016Z","end":"2026-07-16T06:48:47.711859Z","steps":["trace[479526973] 'read index received' (duration: 105.475384ms)","trace[479526973] 'applied index is now lower than readState.Index' (duration: 2.365281ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:47.712091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.04813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-07-16T06:48:47.712454Z","caller":"traceutil/trace.go:171","msg":"trace[213993137] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:51; }","duration":"108.418309ms","start":"2026-07-16T06:48:47.603987Z","end":"2026-07-16T06:48:47.712405Z","steps":["trace[213993137] 'agreement among raft nodes before linearized reading' (duration: 107.933397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:47.71257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.196239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/probes\" ","response":"range_response_count:1 size:1071"} {"level":"info","ts":"2026-07-16T06:48:47.712692Z","caller":"traceutil/trace.go:171","msg":"trace[168420841] range","detail":"{range_begin:/registry/flowschemas/probes; range_end:; response_count:1; response_revision:51; }","duration":"107.289631ms","start":"2026-07-16T06:48:47.605335Z","end":"2026-07-16T06:48:47.712625Z","steps":["trace[168420841] 'agreement among raft nodes before linearized reading' (duration: 107.155027ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:50.309733Z","caller":"traceutil/trace.go:171","msg":"trace[744217800] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"221.524628ms","start":"2026-07-16T06:48:50.088179Z","end":"2026-07-16T06:48:50.309704Z","steps":["trace[744217800] 'process raft request' (duration: 221.379964ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:51.656497Z","caller":"traceutil/trace.go:171","msg":"trace[1281104757] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"203.65357ms","start":"2026-07-16T06:48:51.452816Z","end":"2026-07-16T06:48:51.656469Z","steps":["trace[1281104757] 'process raft request' (duration: 164.929062ms)","trace[1281104757] 'compare' (duration: 38.507812ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:51.659316Z","caller":"traceutil/trace.go:171","msg":"trace[1010370406] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"201.730282ms","start":"2026-07-16T06:48:51.457552Z","end":"2026-07-16T06:48:51.659282Z","steps":["trace[1010370406] 'process raft request' (duration: 201.62264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:52.359381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670601489684374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:48:52.860125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670601489684374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:48:53.360365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670601489684374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:48:53.729748Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.971619463s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T06:48:53.730127Z","caller":"traceutil/trace.go:171","msg":"trace[1114165410] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"1.972143775s","start":"2026-07-16T06:48:51.757967Z","end":"2026-07-16T06:48:53.730111Z","steps":["trace[1114165410] 'process raft request' (duration: 1.972008152s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:53.730215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:51.757942Z","time spent":"1.972224179s","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:55.382229Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.652175816s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T06:48:55.382713Z","caller":"traceutil/trace.go:171","msg":"trace[904102362] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"3.522907936s","start":"2026-07-16T06:48:51.859787Z","end":"2026-07-16T06:48:55.382695Z","steps":["trace[904102362] 'process raft request' (duration: 3.522781473s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:55.382774Z","caller":"traceutil/trace.go:171","msg":"trace[1734527906] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"3.523126801s","start":"2026-07-16T06:48:51.859581Z","end":"2026-07-16T06:48:55.382707Z","steps":["trace[1734527906] 'process raft request' (duration: 3.522955507s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:55.382851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:51.859779Z","time spent":"3.522998438s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:48:55.382888Z","caller":"traceutil/trace.go:171","msg":"trace[1762608447] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"3.522772202s","start":"2026-07-16T06:48:51.860097Z","end":"2026-07-16T06:48:55.382869Z","steps":["trace[1762608447] 'process raft request' (duration: 3.522495405s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:55.382865Z","caller":"traceutil/trace.go:171","msg":"trace[764338316] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"3.523348967s","start":"2026-07-16T06:48:51.859478Z","end":"2026-07-16T06:48:55.382827Z","steps":["trace[764338316] 'process raft request' (duration: 3.523026599s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:55.382992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:51.859552Z","time spent":"3.523334856s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:55.383034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:51.860009Z","time spent":"3.522934317s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:55.383049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:51.859432Z","time spent":"3.523537322s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:55.383217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.524762843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-07-16T06:48:55.383309Z","caller":"traceutil/trace.go:171","msg":"trace[958480313] transaction","detail":"{read_only:false; number_of_response:0; response_revision:288; }","duration":"3.523801158s","start":"2026-07-16T06:48:51.859449Z","end":"2026-07-16T06:48:55.38325Z","steps":["trace[958480313] 'process raft request' (duration: 3.522942947s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:55.383317Z","caller":"traceutil/trace.go:171","msg":"trace[1693242713] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:288; }","duration":"3.524908447s","start":"2026-07-16T06:48:51.858379Z","end":"2026-07-16T06:48:55.383287Z","steps":["trace[1693242713] 'agreement among raft nodes before linearized reading' (duration: 3.52461036s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:55.383381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:51.85836Z","time spent":"3.525011689s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2944,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-16T06:48:55.383385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:51.859432Z","time spent":"3.523925052s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:48:55.383589Z","caller":"traceutil/trace.go:171","msg":"trace[1148900688] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:297; }","duration":"3.524198859s","start":"2026-07-16T06:48:51.858421Z","end":"2026-07-16T06:48:55.38262Z","steps":["trace[1148900688] 'read index received' (duration: 1.871614334s)","trace[1148900688] 'applied index is now lower than readState.Index' (duration: 1.652583505s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:55.383846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.704181162s","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-07-16T06:48:55.383897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709508522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T06:48:55.383904Z","caller":"traceutil/trace.go:171","msg":"trace[646978327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:288; }","duration":"1.704239283s","start":"2026-07-16T06:48:53.679651Z","end":"2026-07-16T06:48:55.38389Z","steps":["trace[646978327] 'agreement among raft nodes before linearized reading' (duration: 1.704142891s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:55.383903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649904689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:48:55.383927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.127045793s","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-07-16T06:48:55.383944Z","caller":"traceutil/trace.go:171","msg":"trace[1083126362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:288; }","duration":"1.709610284s","start":"2026-07-16T06:48:53.67432Z","end":"2026-07-16T06:48:55.383931Z","steps":["trace[1083126362] 'agreement among raft nodes before linearized reading' (duration: 1.709493421s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:55.383947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:53.679617Z","time spent":"1.704321945s","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T06:48:55.383973Z","caller":"traceutil/trace.go:171","msg":"trace[824058121] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:288; }","duration":"1.650002471s","start":"2026-07-16T06:48:53.733941Z","end":"2026-07-16T06:48:55.383943Z","steps":["trace[824058121] 'agreement among raft nodes before linearized reading' (duration: 1.649905088s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:55.383975Z","caller":"traceutil/trace.go:171","msg":"trace[878274230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:288; }","duration":"2.127133135s","start":"2026-07-16T06:48:53.256827Z","end":"2026-07-16T06:48:55.38396Z","steps":["trace[878274230] 'agreement among raft nodes before linearized reading' (duration: 2.127041223s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:55.384018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:53.256796Z","time spent":"2.127215077s","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T06:48:55.38402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:53.733925Z","time spent":"1.650076813s","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-07-16T06:48:55.384023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:53.674299Z","time spent":"1.709673546s","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T06:48:55.384362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.371510435s","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-07-16T06:48:55.38443Z","caller":"traceutil/trace.go:171","msg":"trace[1404012387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:288; }","duration":"2.371595587s","start":"2026-07-16T06:48:53.012818Z","end":"2026-07-16T06:48:55.384414Z","steps":["trace[1404012387] 'agreement among raft nodes before linearized reading' (duration: 2.370954991s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:55.384479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:53.012803Z","time spent":"2.371665109s","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T06:48:56.953096Z","caller":"traceutil/trace.go:171","msg":"trace[1928890445] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"194.822899ms","start":"2026-07-16T06:48:56.758247Z","end":"2026-07-16T06:48:56.95307Z","steps":["trace[1928890445] 'process raft request' (duration: 123.262347ms)","trace[1928890445] 'compare' (duration: 71.178123ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:57.376296Z","caller":"traceutil/trace.go:171","msg":"trace[1302584175] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"185.915897ms","start":"2026-07-16T06:48:57.190351Z","end":"2026-07-16T06:48:57.376267Z","steps":["trace[1302584175] 'process raft request' (duration: 185.735393ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:57.46302Z","caller":"traceutil/trace.go:171","msg":"trace[415724703] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"255.334663ms","start":"2026-07-16T06:48:57.207665Z","end":"2026-07-16T06:48:57.462999Z","steps":["trace[415724703] 'process raft request' (duration: 255.222081ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:57.654522Z","caller":"traceutil/trace.go:171","msg":"trace[1147796308] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"186.759186ms","start":"2026-07-16T06:48:57.467735Z","end":"2026-07-16T06:48:57.654494Z","steps":["trace[1147796308] 'process raft request' (duration: 103.596645ms)","trace[1147796308] 'compare' (duration: 83.039168ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:57.703733Z","caller":"traceutil/trace.go:171","msg":"trace[739753031] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:336; }","duration":"235.39312ms","start":"2026-07-16T06:48:57.468317Z","end":"2026-07-16T06:48:57.70371Z","steps":["trace[739753031] 'read index received' (duration: 103.042283ms)","trace[739753031] 'applied index is now lower than readState.Index' (duration: 132.349817ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:57.70377Z","caller":"traceutil/trace.go:171","msg":"trace[805139950] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"235.511213ms","start":"2026-07-16T06:48:57.46824Z","end":"2026-07-16T06:48:57.703752Z","steps":["trace[805139950] 'process raft request' (duration: 235.39306ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:57.703855Z","caller":"traceutil/trace.go:171","msg":"trace[1393031445] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"235.044512ms","start":"2026-07-16T06:48:57.468794Z","end":"2026-07-16T06:48:57.703839Z","steps":["trace[1393031445] 'process raft request' (duration: 234.874228ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:57.703848Z","caller":"traceutil/trace.go:171","msg":"trace[4148430] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"235.803069ms","start":"2026-07-16T06:48:57.468008Z","end":"2026-07-16T06:48:57.703811Z","steps":["trace[4148430] 'process raft request' (duration: 235.40975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:57.703976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.643476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-16T06:48:57.704033Z","caller":"traceutil/trace.go:171","msg":"trace[2050774921] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:325; }","duration":"235.732508ms","start":"2026-07-16T06:48:57.468283Z","end":"2026-07-16T06:48:57.704016Z","steps":["trace[2050774921] 'agreement among raft nodes before linearized reading' (duration: 235.618226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:57.719241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.26469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:48:57.719308Z","caller":"traceutil/trace.go:171","msg":"trace[91435706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:326; }","duration":"163.377852ms","start":"2026-07-16T06:48:57.555902Z","end":"2026-07-16T06:48:57.71928Z","steps":["trace[91435706] 'agreement among raft nodes before linearized reading' (duration: 163.26889ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:57.71959Z","caller":"traceutil/trace.go:171","msg":"trace[713247582] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"127.602645ms","start":"2026-07-16T06:48:57.591981Z","end":"2026-07-16T06:48:57.719584Z","steps":["trace[713247582] 'process raft request' (duration: 127.04455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.347787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.086154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:48:58.347906Z","caller":"traceutil/trace.go:171","msg":"trace[841341323] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"505.984127ms","start":"2026-07-16T06:48:57.841902Z","end":"2026-07-16T06:48:58.347886Z","steps":["trace[841341323] 'process raft request' (duration: 65.732849ms)","trace[841341323] 'compare' (duration: 439.91281ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:58.347976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.841885Z","time spent":"506.069058ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:58.874393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.986836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:48:58.874657Z","caller":"traceutil/trace.go:171","msg":"trace[1155644766] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"1.031715572s","start":"2026-07-16T06:48:57.842904Z","end":"2026-07-16T06:48:58.87462Z","steps":["trace[1155644766] 'process raft request' (duration: 661.399568ms)","trace[1155644766] 'compare' (duration: 369.768451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:48:58.874808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.842896Z","time spent":"1.031832454s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:48:58.874881Z","caller":"traceutil/trace.go:171","msg":"trace[961873044] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"1.030848212s","start":"2026-07-16T06:48:57.843941Z","end":"2026-07-16T06:48:58.874789Z","steps":["trace[961873044] 'process raft request' (duration: 1.030604636s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.843914Z","time spent":"1.031071137s","remote":"127.0.0.1:50808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:48:58.875024Z","caller":"traceutil/trace.go:171","msg":"trace[1737524254] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"1.030404881s","start":"2026-07-16T06:48:57.844572Z","end":"2026-07-16T06:48:58.874977Z","steps":["trace[1737524254] 'process raft request' (duration: 1.030165166s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:58.875341Z","caller":"traceutil/trace.go:171","msg":"trace[1504945008] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:347; }","duration":"1.018426397s","start":"2026-07-16T06:48:57.85684Z","end":"2026-07-16T06:48:58.875266Z","steps":["trace[1504945008] 'read index received' (duration: 50.807005ms)","trace[1504945008] 'applied index is now lower than readState.Index' (duration: 967.615642ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:58.875393Z","caller":"traceutil/trace.go:171","msg":"trace[363409681] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"523.214178ms","start":"2026-07-16T06:48:58.352155Z","end":"2026-07-16T06:48:58.875369Z","steps":["trace[363409681] 'process raft request' (duration: 523.004513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.844529Z","time spent":"1.030641767s","remote":"127.0.0.1:50834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:48:58.875512Z","caller":"traceutil/trace.go:171","msg":"trace[393923680] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"1.029580391s","start":"2026-07-16T06:48:57.845907Z","end":"2026-07-16T06:48:58.875488Z","steps":["trace[393923680] 'process raft request' (duration: 1.028998967s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.684062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-07-16T06:48:58.875657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.881957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-16T06:48:58.87566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.845893Z","time spent":"1.029672713s","remote":"127.0.0.1:51178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:48:58.875682Z","caller":"traceutil/trace.go:171","msg":"trace[124024285] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:338; }","duration":"507.758594ms","start":"2026-07-16T06:48:58.367901Z","end":"2026-07-16T06:48:58.87566Z","steps":["trace[124024285] 'agreement among raft nodes before linearized reading' (duration: 507.655382ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:58.875717Z","caller":"traceutil/trace.go:171","msg":"trace[183671341] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:338; }","duration":"918.969069ms","start":"2026-07-16T06:48:57.956729Z","end":"2026-07-16T06:48:58.875698Z","steps":["trace[183671341] 'agreement among raft nodes before linearized reading' (duration: 918.824055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.010599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-07-16T06:48:58.875729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:58.36788Z","time spent":"507.842505ms","remote":"127.0.0.1:51136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-07-16T06:48:58.875788Z","caller":"traceutil/trace.go:171","msg":"trace[1117501506] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:338; }","duration":"508.092232ms","start":"2026-07-16T06:48:58.367679Z","end":"2026-07-16T06:48:58.875771Z","steps":["trace[1117501506] 'agreement among raft nodes before linearized reading' (duration: 508.01319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.735242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-07-16T06:48:58.875847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:58.367656Z","time spent":"508.179964ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-07-16T06:48:58.875872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.483358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-16T06:48:58.875866Z","caller":"traceutil/trace.go:171","msg":"trace[908939323] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:338; }","duration":"718.818814ms","start":"2026-07-16T06:48:58.157032Z","end":"2026-07-16T06:48:58.875851Z","steps":["trace[908939323] 'agreement among raft nodes before linearized reading' (duration: 718.716062ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:58.875902Z","caller":"traceutil/trace.go:171","msg":"trace[2071438924] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:338; }","duration":"867.549929ms","start":"2026-07-16T06:48:58.008342Z","end":"2026-07-16T06:48:58.875892Z","steps":["trace[2071438924] 'agreement among raft nodes before linearized reading' (duration: 867.501338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:58.157015Z","time spent":"718.891956ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-16T06:48:58.875921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000871141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:48:58.875516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:58.352129Z","time spent":"523.33262ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T06:48:58.875858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.075967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-16T06:48:58.876013Z","caller":"traceutil/trace.go:171","msg":"trace[1668862026] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:338; }","duration":"969.249761ms","start":"2026-07-16T06:48:57.906756Z","end":"2026-07-16T06:48:58.876006Z","steps":["trace[1668862026] 'agreement among raft nodes before linearized reading' (duration: 968.998075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.996374ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:48:58.87604Z","caller":"traceutil/trace.go:171","msg":"trace[488756553] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:338; }","duration":"147.069146ms","start":"2026-07-16T06:48:58.728965Z","end":"2026-07-16T06:48:58.876034Z","steps":["trace[488756553] 'agreement among raft nodes before linearized reading' (duration: 146.987674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.876041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.906746Z","time spent":"969.288022ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-07-16T06:48:58.875674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006239128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-16T06:48:58.875644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.353439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-16T06:48:58.876153Z","caller":"traceutil/trace.go:171","msg":"trace[638982292] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:338; }","duration":"1.006707978s","start":"2026-07-16T06:48:57.869391Z","end":"2026-07-16T06:48:58.876099Z","steps":["trace[638982292] 'agreement among raft nodes before linearized reading' (duration: 1.006125125s)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:58.87622Z","caller":"traceutil/trace.go:171","msg":"trace[1751842077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:338; }","duration":"768.934552ms","start":"2026-07-16T06:48:58.107246Z","end":"2026-07-16T06:48:58.876181Z","steps":["trace[1751842077] 'agreement among raft nodes before linearized reading' (duration: 768.294117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.875823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.209894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-16T06:48:58.87629Z","caller":"traceutil/trace.go:171","msg":"trace[984373403] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:338; }","duration":"819.680295ms","start":"2026-07-16T06:48:58.056593Z","end":"2026-07-16T06:48:58.876273Z","steps":["trace[984373403] 'agreement among raft nodes before linearized reading' (duration: 819.197233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.876343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:58.056582Z","time spent":"819.747667ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-16T06:48:58.876224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.869375Z","time spent":"1.006831582s","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-16T06:48:58.875711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018815356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-16T06:48:58.87662Z","caller":"traceutil/trace.go:171","msg":"trace[4620355] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:338; }","duration":"1.019794189s","start":"2026-07-16T06:48:57.856805Z","end":"2026-07-16T06:48:58.876599Z","steps":["trace[4620355] 'agreement among raft nodes before linearized reading' (duration: 1.018688582s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.87667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.856792Z","time spent":"1.019862411s","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-16T06:48:58.875771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.956714Z","time spent":"919.04975ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-07-16T06:48:58.875933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:58.00832Z","time spent":"867.607821ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-07-16T06:48:58.875956Z","caller":"traceutil/trace.go:171","msg":"trace[1524086409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:338; }","duration":"1.000957693s","start":"2026-07-16T06:48:57.874989Z","end":"2026-07-16T06:48:58.875947Z","steps":["trace[1524086409] 'agreement among raft nodes before linearized reading' (duration: 1.000890261s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:58.876884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:57.87498Z","time spent":"1.001889725s","remote":"127.0.0.1:50714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:48:58.876968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:48:58.10723Z","time spent":"769.033045ms","remote":"127.0.0.1:50964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-07-16T06:48:59.053562Z","caller":"traceutil/trace.go:171","msg":"trace[793507277] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"111.963122ms","start":"2026-07-16T06:48:58.941561Z","end":"2026-07-16T06:48:59.053524Z","steps":["trace[793507277] 'process raft request' (duration: 62.353286ms)","trace[793507277] 'compare' (duration: 49.375451ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:59.053866Z","caller":"traceutil/trace.go:171","msg":"trace[203595997] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"111.970701ms","start":"2026-07-16T06:48:58.941829Z","end":"2026-07-16T06:48:59.0538Z","steps":["trace[203595997] 'process raft request' (duration: 111.630994ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.05494Z","caller":"traceutil/trace.go:171","msg":"trace[1026103679] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"112.646568ms","start":"2026-07-16T06:48:58.942255Z","end":"2026-07-16T06:48:59.054902Z","steps":["trace[1026103679] 'process raft request' (duration: 111.263436ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.0553Z","caller":"traceutil/trace.go:171","msg":"trace[1916164538] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"112.73712ms","start":"2026-07-16T06:48:58.942507Z","end":"2026-07-16T06:48:59.055244Z","steps":["trace[1916164538] 'process raft request' (duration: 112.30676ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.055584Z","caller":"traceutil/trace.go:171","msg":"trace[1689295264] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"110.520368ms","start":"2026-07-16T06:48:58.945046Z","end":"2026-07-16T06:48:59.055566Z","steps":["trace[1689295264] 'process raft request' (duration: 110.21012ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.055732Z","caller":"traceutil/trace.go:171","msg":"trace[287495609] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"111.834799ms","start":"2026-07-16T06:48:58.943236Z","end":"2026-07-16T06:48:59.055071Z","steps":["trace[287495609] 'process raft request' (duration: 111.672435ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.055832Z","caller":"traceutil/trace.go:171","msg":"trace[1839063231] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:366; }","duration":"112.448284ms","start":"2026-07-16T06:48:58.943373Z","end":"2026-07-16T06:48:59.055822Z","steps":["trace[1839063231] 'read index received' (duration: 11.542383ms)","trace[1839063231] 'applied index is now lower than readState.Index' (duration: 100.905101ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:48:59.055892Z","caller":"traceutil/trace.go:171","msg":"trace[1484774265] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"110.283002ms","start":"2026-07-16T06:48:58.945596Z","end":"2026-07-16T06:48:59.055879Z","steps":["trace[1484774265] 'process raft request' (duration: 109.919063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:48:59.055939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.546366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-16T06:48:59.056162Z","caller":"traceutil/trace.go:171","msg":"trace[1569424440] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:358; }","duration":"112.800692ms","start":"2026-07-16T06:48:58.943352Z","end":"2026-07-16T06:48:59.056153Z","steps":["trace[1569424440] 'agreement among raft nodes before linearized reading' (duration: 112.511005ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.056287Z","caller":"traceutil/trace.go:171","msg":"trace[121946713] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"111.89811ms","start":"2026-07-16T06:48:58.944366Z","end":"2026-07-16T06:48:59.056265Z","steps":["trace[121946713] 'process raft request' (duration: 110.589569ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.266982Z","caller":"traceutil/trace.go:171","msg":"trace[585684411] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"129.381544ms","start":"2026-07-16T06:48:59.137572Z","end":"2026-07-16T06:48:59.266953Z","steps":["trace[585684411] 'process raft request' (duration: 129.20621ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:48:59.267479Z","caller":"traceutil/trace.go:171","msg":"trace[1296524464] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"132.237741ms","start":"2026-07-16T06:48:59.135181Z","end":"2026-07-16T06:48:59.267419Z","steps":["trace[1296524464] 'process raft request' (duration: 52.408372ms)","trace[1296524464] 'compare' (duration: 78.875027ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:01.110371Z","caller":"traceutil/trace.go:171","msg":"trace[638254015] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"256.6149ms","start":"2026-07-16T06:49:00.853732Z","end":"2026-07-16T06:49:01.110347Z","steps":["trace[638254015] 'process raft request' (duration: 251.247047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:01.110487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.222151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:01.110534Z","caller":"traceutil/trace.go:171","msg":"trace[193487478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:388; }","duration":"153.332103ms","start":"2026-07-16T06:49:00.957185Z","end":"2026-07-16T06:49:01.110518Z","steps":["trace[193487478] 'agreement among raft nodes before linearized reading' (duration: 153.227551ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:01.110334Z","caller":"traceutil/trace.go:171","msg":"trace[380672537] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"153.064177ms","start":"2026-07-16T06:49:00.957233Z","end":"2026-07-16T06:49:01.110297Z","steps":["trace[380672537] 'read index received' (duration: 147.837648ms)","trace[380672537] 'applied index is now lower than readState.Index' (duration: 5.224939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:01.869915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.2586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T06:49:01.870039Z","caller":"traceutil/trace.go:171","msg":"trace[1391830263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"115.421824ms","start":"2026-07-16T06:49:01.754595Z","end":"2026-07-16T06:49:01.870017Z","steps":["trace[1391830263] 'range keys from in-memory index tree' (duration: 115.173718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:01.870103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.287112ms","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-07-16T06:49:01.870177Z","caller":"traceutil/trace.go:171","msg":"trace[1676428130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"115.425995ms","start":"2026-07-16T06:49:01.754734Z","end":"2026-07-16T06:49:01.87016Z","steps":["trace[1676428130] 'range keys from in-memory index tree' (duration: 115.080897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:01.870394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.411068ms","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-07-16T06:49:01.870487Z","caller":"traceutil/trace.go:171","msg":"trace[228979319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"123.560191ms","start":"2026-07-16T06:49:01.7469Z","end":"2026-07-16T06:49:01.87046Z","steps":["trace[228979319] 'range keys from in-memory index tree' (duration: 122.986498ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:04.093375Z","caller":"traceutil/trace.go:171","msg":"trace[1353704673] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"144.815537ms","start":"2026-07-16T06:49:03.948535Z","end":"2026-07-16T06:49:04.09335Z","steps":["trace[1353704673] 'process raft request' (duration: 144.633063ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:04.093641Z","caller":"traceutil/trace.go:171","msg":"trace[648613714] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"144.702705ms","start":"2026-07-16T06:49:03.948918Z","end":"2026-07-16T06:49:04.093621Z","steps":["trace[648613714] 'process raft request' (duration: 144.395568ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:04.176834Z","caller":"traceutil/trace.go:171","msg":"trace[1001866283] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:412; }","duration":"220.220611ms","start":"2026-07-16T06:49:03.956572Z","end":"2026-07-16T06:49:04.176793Z","steps":["trace[1001866283] 'read index received' (duration: 76.697203ms)","trace[1001866283] 'applied index is now lower than readState.Index' (duration: 143.521428ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:04.176998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.404275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:04.177083Z","caller":"traceutil/trace.go:171","msg":"trace[1559059017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:398; }","duration":"220.536458ms","start":"2026-07-16T06:49:03.956528Z","end":"2026-07-16T06:49:04.177064Z","steps":["trace[1559059017] 'agreement among raft nodes before linearized reading' (duration: 220.370395ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:08.246339Z","caller":"traceutil/trace.go:171","msg":"trace[24715874] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"194.604311ms","start":"2026-07-16T06:49:08.051709Z","end":"2026-07-16T06:49:08.246314Z","steps":["trace[24715874] 'process raft request' (duration: 194.463728ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:08.265741Z","caller":"traceutil/trace.go:171","msg":"trace[1210900529] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"209.401312ms","start":"2026-07-16T06:49:08.056308Z","end":"2026-07-16T06:49:08.26571Z","steps":["trace[1210900529] 'process raft request' (duration: 209.180047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:14.417327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.290741ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T06:49:16.225195Z","caller":"traceutil/trace.go:171","msg":"trace[1570227610] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"212.566185ms","start":"2026-07-16T06:49:16.012603Z","end":"2026-07-16T06:49:16.225169Z","steps":["trace[1570227610] 'process raft request' (duration: 212.420331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:31.730686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.733917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"info","ts":"2026-07-16T06:49:31.730778Z","caller":"traceutil/trace.go:171","msg":"trace[860594533] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:490; }","duration":"164.863561ms","start":"2026-07-16T06:49:31.565894Z","end":"2026-07-16T06:49:31.730757Z","steps":["trace[860594533] 'range keys from in-memory index tree' (duration: 164.633246ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:32.455568Z","caller":"traceutil/trace.go:171","msg":"trace[954252793] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"101.69498ms","start":"2026-07-16T06:49:32.353852Z","end":"2026-07-16T06:49:32.455547Z","steps":["trace[954252793] 'process raft request' (duration: 101.559698ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:33.114502Z","caller":"traceutil/trace.go:171","msg":"trace[553247919] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"580.204036ms","start":"2026-07-16T06:49:32.534212Z","end":"2026-07-16T06:49:33.114416Z","steps":["trace[553247919] 'process raft request' (duration: 580.065674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:33.114683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:32.534175Z","time spent":"580.412031ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:49:33.121647Z","caller":"traceutil/trace.go:171","msg":"trace[1385450544] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:516; }","duration":"428.478935ms","start":"2026-07-16T06:49:32.693146Z","end":"2026-07-16T06:49:33.121625Z","steps":["trace[1385450544] 'read index received' (duration: 421.238304ms)","trace[1385450544] 'applied index is now lower than readState.Index' (duration: 7.239761ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:33.121867Z","caller":"traceutil/trace.go:171","msg":"trace[678929104] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"517.78586ms","start":"2026-07-16T06:49:32.604062Z","end":"2026-07-16T06:49:33.121848Z","steps":["trace[678929104] 'process raft request' (duration: 517.282741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:33.12188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.719119ms","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-07-16T06:49:33.121919Z","caller":"traceutil/trace.go:171","msg":"trace[146372917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"428.80612ms","start":"2026-07-16T06:49:32.693105Z","end":"2026-07-16T06:49:33.121911Z","steps":["trace[146372917] 'agreement among raft nodes before linearized reading' (duration: 428.671748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:33.121911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.75442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-16T06:49:33.121947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:32.693085Z","time spent":"428.855412ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T06:49:33.121946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:32.604024Z","time spent":"517.885301ms","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:49:33.121954Z","caller":"traceutil/trace.go:171","msg":"trace[1127223836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"428.837471ms","start":"2026-07-16T06:49:32.693105Z","end":"2026-07-16T06:49:33.121942Z","steps":["trace[1127223836] 'agreement among raft nodes before linearized reading' (duration: 428.764959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:33.122016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:32.693085Z","time spent":"428.925303ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T06:49:33.122096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.263701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:33.122117Z","caller":"traceutil/trace.go:171","msg":"trace[579531740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"167.311182ms","start":"2026-07-16T06:49:32.954798Z","end":"2026-07-16T06:49:33.12211Z","steps":["trace[579531740] 'agreement among raft nodes before linearized reading' (duration: 167.012796ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.079691Z","caller":"traceutil/trace.go:171","msg":"trace[436278351] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"299.161817ms","start":"2026-07-16T06:49:34.780499Z","end":"2026-07-16T06:49:35.07966Z","steps":["trace[436278351] 'process raft request' (duration: 298.996814ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089365Z","caller":"traceutil/trace.go:171","msg":"trace[804706379] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:555; }","duration":"134.214702ms","start":"2026-07-16T06:49:34.955137Z","end":"2026-07-16T06:49:35.089352Z","steps":["trace[804706379] 'read index received' (duration: 124.399137ms)","trace[804706379] 'applied index is now lower than readState.Index' (duration: 9.814665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:35.089458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.308483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:35.089479Z","caller":"traceutil/trace.go:171","msg":"trace[1393274234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"134.363734ms","start":"2026-07-16T06:49:34.955109Z","end":"2026-07-16T06:49:35.089473Z","steps":["trace[1393274234] 'agreement among raft nodes before linearized reading' (duration: 134.306673ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089593Z","caller":"traceutil/trace.go:171","msg":"trace[2018575077] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"292.735521ms","start":"2026-07-16T06:49:34.796812Z","end":"2026-07-16T06:49:35.089547Z","steps":["trace[2018575077] 'process raft request' (duration: 292.285392ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089654Z","caller":"traceutil/trace.go:171","msg":"trace[1756184635] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"290.948208ms","start":"2026-07-16T06:49:34.7987Z","end":"2026-07-16T06:49:35.089648Z","steps":["trace[1756184635] 'process raft request' (duration: 290.456698ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089717Z","caller":"traceutil/trace.go:171","msg":"trace[1341467412] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"293.024826ms","start":"2026-07-16T06:49:34.796674Z","end":"2026-07-16T06:49:35.089698Z","steps":["trace[1341467412] 'process raft request' (duration: 292.313772ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089754Z","caller":"traceutil/trace.go:171","msg":"trace[1706232053] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"300.49665ms","start":"2026-07-16T06:49:34.789172Z","end":"2026-07-16T06:49:35.089669Z","steps":["trace[1706232053] 'process raft request' (duration: 299.784997ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089809Z","caller":"traceutil/trace.go:171","msg":"trace[1008456389] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"136.90317ms","start":"2026-07-16T06:49:34.9529Z","end":"2026-07-16T06:49:35.089804Z","steps":["trace[1008456389] 'process raft request' (duration: 136.412931ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089891Z","caller":"traceutil/trace.go:171","msg":"trace[1500355957] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"264.636236ms","start":"2026-07-16T06:49:34.825249Z","end":"2026-07-16T06:49:35.089886Z","steps":["trace[1500355957] 'process raft request' (duration: 263.945283ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089911Z","caller":"traceutil/trace.go:171","msg":"trace[1498175072] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"258.72755ms","start":"2026-07-16T06:49:34.831172Z","end":"2026-07-16T06:49:35.0899Z","steps":["trace[1498175072] 'process raft request' (duration: 258.057747ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089947Z","caller":"traceutil/trace.go:171","msg":"trace[1515065590] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"247.858234ms","start":"2026-07-16T06:49:34.842083Z","end":"2026-07-16T06:49:35.089941Z","steps":["trace[1515065590] 'process raft request' (duration: 247.186091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:35.089969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:34.789161Z","time spent":"300.648684ms","remote":"127.0.0.1:50778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:49:35.090043Z","caller":"traceutil/trace.go:171","msg":"trace[533220744] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"303.591326ms","start":"2026-07-16T06:49:34.786427Z","end":"2026-07-16T06:49:35.090018Z","steps":["trace[533220744] 'process raft request' (duration: 302.465696ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089778Z","caller":"traceutil/trace.go:171","msg":"trace[129201267] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"292.987195ms","start":"2026-07-16T06:49:34.796779Z","end":"2026-07-16T06:49:35.089766Z","steps":["trace[129201267] 'process raft request' (duration: 292.282321ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:35.089627Z","caller":"traceutil/trace.go:171","msg":"trace[84594367] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"290.920327ms","start":"2026-07-16T06:49:34.798696Z","end":"2026-07-16T06:49:35.089616Z","steps":["trace[84594367] 'process raft request' (duration: 290.433378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:35.090115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:34.786406Z","time spent":"303.672917ms","remote":"127.0.0.1:50778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:49:41.342952Z","caller":"traceutil/trace.go:171","msg":"trace[2024010259] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"113.898391ms","start":"2026-07-16T06:49:41.228979Z","end":"2026-07-16T06:49:41.342877Z","steps":["trace[2024010259] 'process raft request' (duration: 113.659767ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:41.731338Z","caller":"traceutil/trace.go:171","msg":"trace[1802425729] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"498.955827ms","start":"2026-07-16T06:49:41.232354Z","end":"2026-07-16T06:49:41.73131Z","steps":["trace[1802425729] 'process raft request' (duration: 498.52723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:41.731467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:41.232347Z","time spent":"499.050278ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:49:41.73157Z","caller":"traceutil/trace.go:171","msg":"trace[1763959593] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"474.649529ms","start":"2026-07-16T06:49:41.256898Z","end":"2026-07-16T06:49:41.731548Z","steps":["trace[1763959593] 'process raft request' (duration: 474.310982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:41.73176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:41.256859Z","time spent":"474.766501ms","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:49:41.731879Z","caller":"traceutil/trace.go:171","msg":"trace[1949479121] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"499.272623ms","start":"2026-07-16T06:49:41.232574Z","end":"2026-07-16T06:49:41.731847Z","steps":["trace[1949479121] 'process raft request' (duration: 498.432608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:41.732084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:41.232551Z","time spent":"499.442696ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:49:41.946809Z","caller":"traceutil/trace.go:171","msg":"trace[1104108165] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"590.450461ms","start":"2026-07-16T06:49:41.356328Z","end":"2026-07-16T06:49:41.946778Z","steps":["trace[1104108165] 'process raft request' (duration: 570.961376ms)","trace[1104108165] 'compare' (duration: 19.374573ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:41.947003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:41.356304Z","time spent":"590.613173ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:49:41.992865Z","caller":"traceutil/trace.go:171","msg":"trace[758566454] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"194.992916ms","start":"2026-07-16T06:49:41.797849Z","end":"2026-07-16T06:49:41.992842Z","steps":["trace[758566454] 'process raft request' (duration: 194.844104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:42.833831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.611121ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:49:42.833944Z","caller":"traceutil/trace.go:171","msg":"trace[1535940219] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"826.028781ms","start":"2026-07-16T06:49:42.00789Z","end":"2026-07-16T06:49:42.833919Z","steps":["trace[1535940219] 'process raft request' (duration: 241.246297ms)","trace[1535940219] 'compare' (duration: 584.479539ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:42.834043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:42.007876Z","time spent":"826.136103ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:49:42.857958Z","caller":"traceutil/trace.go:171","msg":"trace[303394624] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"351.059197ms","start":"2026-07-16T06:49:42.506864Z","end":"2026-07-16T06:49:42.857924Z","steps":["trace[303394624] 'read index received' (duration: 350.10288ms)","trace[303394624] 'applied index is now lower than readState.Index' (duration: 954.657µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:42.858074Z","caller":"traceutil/trace.go:171","msg":"trace[199768343] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"445.230998ms","start":"2026-07-16T06:49:42.412798Z","end":"2026-07-16T06:49:42.85803Z","steps":["trace[199768343] 'process raft request' (duration: 444.383782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:42.858202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.317241ms","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-07-16T06:49:42.858244Z","caller":"traceutil/trace.go:171","msg":"trace[171477559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"351.422633ms","start":"2026-07-16T06:49:42.506811Z","end":"2026-07-16T06:49:42.858234Z","steps":["trace[171477559] 'agreement among raft nodes before linearized reading' (duration: 351.25667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:42.858283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:42.506788Z","time spent":"351.486204ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T06:49:42.858349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:42.412766Z","time spent":"445.40847ms","remote":"127.0.0.1:50820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:49:43.461047Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670601489685092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:49:43.962085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670601489685092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T06:49:44.038994Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.115787372s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T06:49:44.142161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.912091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T06:49:44.142405Z","caller":"traceutil/trace.go:171","msg":"trace[576042449] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.219314653s","start":"2026-07-16T06:49:42.923065Z","end":"2026-07-16T06:49:44.142379Z","steps":["trace[576042449] 'process raft request' (duration: 1.116119488s)","trace[576042449] 'compare' (duration: 101.449135ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:44.143586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:42.923027Z","time spent":"1.219433075s","remote":"127.0.0.1:51000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T06:49:44.143951Z","caller":"traceutil/trace.go:171","msg":"trace[1877389620] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"1.182217618s","start":"2026-07-16T06:49:42.960174Z","end":"2026-07-16T06:49:44.142391Z","steps":["trace[1877389620] 'read index received' (duration: 1.079003042s)","trace[1877389620] 'applied index is now lower than readState.Index' (duration: 103.210306ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:44.18948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229287625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:44.189571Z","caller":"traceutil/trace.go:171","msg":"trace[1184327495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"1.229430727s","start":"2026-07-16T06:49:42.960117Z","end":"2026-07-16T06:49:44.189548Z","steps":["trace[1184327495] 'agreement among raft nodes before linearized reading' (duration: 1.182384631s)","trace[1184327495] 'range keys from in-memory index tree' (duration: 46.907234ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:44.189573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.774514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-16T06:49:44.189614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:42.960094Z","time spent":"1.229511609s","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T06:49:44.189661Z","caller":"traceutil/trace.go:171","msg":"trace[126795663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"452.917165ms","start":"2026-07-16T06:49:43.736721Z","end":"2026-07-16T06:49:44.189638Z","steps":["trace[126795663] 'agreement among raft nodes before linearized reading' (duration: 452.670401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:44.189698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.848917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T06:49:44.18978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:43.736698Z","time spent":"453.070198ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T06:49:44.189823Z","caller":"traceutil/trace.go:171","msg":"trace[1565911055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"452.071921ms","start":"2026-07-16T06:49:43.737722Z","end":"2026-07-16T06:49:44.189794Z","steps":["trace[1565911055] 'agreement among raft nodes before linearized reading' (duration: 451.653584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:44.189888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:43.737687Z","time spent":"452.190232ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T06:49:44.189919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.212622ms","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-07-16T06:49:44.190175Z","caller":"traceutil/trace.go:171","msg":"trace[297690264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"837.567258ms","start":"2026-07-16T06:49:43.352583Z","end":"2026-07-16T06:49:44.19015Z","steps":["trace[297690264] 'agreement among raft nodes before linearized reading' (duration: 836.981388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:44.190261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:43.352545Z","time spent":"837.7044ms","remote":"127.0.0.1:51020","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":"info","ts":"2026-07-16T06:49:46.239955Z","caller":"traceutil/trace.go:171","msg":"trace[1675632421] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"225.994448ms","start":"2026-07-16T06:49:46.013939Z","end":"2026-07-16T06:49:46.239933Z","steps":["trace[1675632421] 'process raft request' (duration: 225.776925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:47.251354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.518234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:49:47.251385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.093921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:47.25145Z","caller":"traceutil/trace.go:171","msg":"trace[186868572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"293.683906ms","start":"2026-07-16T06:49:46.957744Z","end":"2026-07-16T06:49:47.251428Z","steps":["trace[186868572] 'range keys from in-memory index tree' (duration: 293.421252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:47.251472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.37103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T06:49:47.251569Z","caller":"traceutil/trace.go:171","msg":"trace[1443142256] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:667; }","duration":"132.514272ms","start":"2026-07-16T06:49:47.119039Z","end":"2026-07-16T06:49:47.251553Z","steps":["trace[1443142256] 'range keys from in-memory index tree' (duration: 132.248488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:47.251357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.563029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-16T06:49:47.251477Z","caller":"traceutil/trace.go:171","msg":"trace[131563106] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:667; }","duration":"624.265133ms","start":"2026-07-16T06:49:46.627191Z","end":"2026-07-16T06:49:47.251456Z","steps":["trace[131563106] 'count revisions from in-memory index tree' (duration: 623.944388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:47.251742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.645917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w677l\" ","response":"range_response_count:1 size:17524"} {"level":"warn","ts":"2026-07-16T06:49:47.25175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.115502ms","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-07-16T06:49:47.251813Z","caller":"traceutil/trace.go:171","msg":"trace[921911329] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w677l; range_end:; response_count:1; response_revision:667; }","duration":"241.771749ms","start":"2026-07-16T06:49:47.010022Z","end":"2026-07-16T06:49:47.251794Z","steps":["trace[921911329] 'range keys from in-memory index tree' (duration: 241.375722ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:47.251854Z","caller":"traceutil/trace.go:171","msg":"trace[962190647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"343.326186ms","start":"2026-07-16T06:49:46.908507Z","end":"2026-07-16T06:49:47.251833Z","steps":["trace[962190647] 'range keys from in-memory index tree' (duration: 342.818178ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:47.251749Z","caller":"traceutil/trace.go:171","msg":"trace[549209506] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:667; }","duration":"649.012277ms","start":"2026-07-16T06:49:46.602706Z","end":"2026-07-16T06:49:47.251719Z","steps":["trace[549209506] 'count revisions from in-memory index tree' (duration: 648.301615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:47.252028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:46.602672Z","time spent":"649.332512ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":6,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-16T06:49:47.251921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:46.908476Z","time spent":"343.433578ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T06:49:47.251809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:49:46.627157Z","time spent":"624.632489ms","remote":"127.0.0.1:50968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-07-16T06:49:47.518051Z","caller":"traceutil/trace.go:171","msg":"trace[1481971914] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:701; }","duration":"240.514069ms","start":"2026-07-16T06:49:47.277503Z","end":"2026-07-16T06:49:47.518017Z","steps":["trace[1481971914] 'read index received' (duration: 77.447058ms)","trace[1481971914] 'applied index is now lower than readState.Index' (duration: 163.065791ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:47.518133Z","caller":"traceutil/trace.go:171","msg":"trace[1622775591] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"248.486621ms","start":"2026-07-16T06:49:47.26961Z","end":"2026-07-16T06:49:47.518097Z","steps":["trace[1622775591] 'process raft request' (duration: 245.333879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:47.518206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.675161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:47.518243Z","caller":"traceutil/trace.go:171","msg":"trace[654138861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:669; }","duration":"240.745812ms","start":"2026-07-16T06:49:47.277487Z","end":"2026-07-16T06:49:47.518232Z","steps":["trace[654138861] 'agreement among raft nodes before linearized reading' (duration: 240.645051ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:47.52955Z","caller":"traceutil/trace.go:171","msg":"trace[874648576] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"136.828115ms","start":"2026-07-16T06:49:47.392697Z","end":"2026-07-16T06:49:47.529525Z","steps":["trace[874648576] 'process raft request' (duration: 136.649562ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:48.042922Z","caller":"traceutil/trace.go:171","msg":"trace[339815470] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"102.179925ms","start":"2026-07-16T06:49:47.940711Z","end":"2026-07-16T06:49:48.042891Z","steps":["trace[339815470] 'read index received' (duration: 102.009453ms)","trace[339815470] 'applied index is now lower than readState.Index' (duration: 168.912µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:48.043045Z","caller":"traceutil/trace.go:171","msg":"trace[74556405] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"213.439245ms","start":"2026-07-16T06:49:47.829574Z","end":"2026-07-16T06:49:48.043013Z","steps":["trace[74556405] 'process raft request' (duration: 213.112349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:48.043295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.555462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:48.043365Z","caller":"traceutil/trace.go:171","msg":"trace[650608306] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:680; }","duration":"102.690565ms","start":"2026-07-16T06:49:47.940652Z","end":"2026-07-16T06:49:48.043343Z","steps":["trace[650608306] 'agreement among raft nodes before linearized reading' (duration: 102.40247ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:48.278912Z","caller":"traceutil/trace.go:171","msg":"trace[1955142917] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"108.022316ms","start":"2026-07-16T06:49:48.170856Z","end":"2026-07-16T06:49:48.278878Z","steps":["trace[1955142917] 'process raft request' (duration: 104.07362ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:48.555756Z","caller":"traceutil/trace.go:171","msg":"trace[158731767] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"252.185899ms","start":"2026-07-16T06:49:48.30354Z","end":"2026-07-16T06:49:48.555726Z","steps":["trace[158731767] 'process raft request' (duration: 251.323284ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:48.565307Z","caller":"traceutil/trace.go:171","msg":"trace[1544958629] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"255.795708ms","start":"2026-07-16T06:49:48.309488Z","end":"2026-07-16T06:49:48.565283Z","steps":["trace[1544958629] 'process raft request' (duration: 255.628296ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:48.565671Z","caller":"traceutil/trace.go:171","msg":"trace[249117984] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"235.033715ms","start":"2026-07-16T06:49:48.3306Z","end":"2026-07-16T06:49:48.565634Z","steps":["trace[249117984] 'process raft request' (duration: 234.651449ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:48.565702Z","caller":"traceutil/trace.go:171","msg":"trace[1587880449] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"225.236013ms","start":"2026-07-16T06:49:48.340457Z","end":"2026-07-16T06:49:48.565693Z","steps":["trace[1587880449] 'process raft request' (duration: 225.05793ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:50.300886Z","caller":"traceutil/trace.go:171","msg":"trace[1618113039] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"114.564222ms","start":"2026-07-16T06:49:50.186287Z","end":"2026-07-16T06:49:50.300851Z","steps":["trace[1618113039] 'process raft request' (duration: 113.683398ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:53.830997Z","caller":"traceutil/trace.go:171","msg":"trace[846879118] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"158.752675ms","start":"2026-07-16T06:49:53.672216Z","end":"2026-07-16T06:49:53.830968Z","steps":["trace[846879118] 'read index received' (duration: 104.141136ms)","trace[846879118] 'applied index is now lower than readState.Index' (duration: 54.610089ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:53.831266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.025039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2075"} {"level":"info","ts":"2026-07-16T06:49:53.831328Z","caller":"traceutil/trace.go:171","msg":"trace[416776400] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:760; }","duration":"159.15124ms","start":"2026-07-16T06:49:53.672165Z","end":"2026-07-16T06:49:53.831316Z","steps":["trace[416776400] 'agreement among raft nodes before linearized reading' (duration: 158.912317ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:53.831228Z","caller":"traceutil/trace.go:171","msg":"trace[1574779884] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"176.50944ms","start":"2026-07-16T06:49:53.654668Z","end":"2026-07-16T06:49:53.831178Z","steps":["trace[1574779884] 'process raft request' (duration: 121.648357ms)","trace[1574779884] 'compare' (duration: 54.506268ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:49:53.971183Z","caller":"traceutil/trace.go:171","msg":"trace[1140902219] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"102.091814ms","start":"2026-07-16T06:49:53.869061Z","end":"2026-07-16T06:49:53.971153Z","steps":["trace[1140902219] 'read index received' (duration: 97.391068ms)","trace[1140902219] 'applied index is now lower than readState.Index' (duration: 4.699546ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:49:53.971372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.276246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:53.971233Z","caller":"traceutil/trace.go:171","msg":"trace[496867350] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"115.427099ms","start":"2026-07-16T06:49:53.855782Z","end":"2026-07-16T06:49:53.971209Z","steps":["trace[496867350] 'process raft request' (duration: 110.748853ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:53.971426Z","caller":"traceutil/trace.go:171","msg":"trace[561672213] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:761; }","duration":"102.399788ms","start":"2026-07-16T06:49:53.869015Z","end":"2026-07-16T06:49:53.971414Z","steps":["trace[561672213] 'agreement among raft nodes before linearized reading' (duration: 102.268606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:53.971559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.432669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:53.971698Z","caller":"traceutil/trace.go:171","msg":"trace[977164619] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:761; }","duration":"102.620732ms","start":"2026-07-16T06:49:53.869064Z","end":"2026-07-16T06:49:53.971685Z","steps":["trace[977164619] 'agreement among raft nodes before linearized reading' (duration: 102.444319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:49:53.971923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.817678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:49:53.971995Z","caller":"traceutil/trace.go:171","msg":"trace[966693700] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:761; }","duration":"101.94729ms","start":"2026-07-16T06:49:53.870033Z","end":"2026-07-16T06:49:53.971981Z","steps":["trace[966693700] 'agreement among raft nodes before linearized reading' (duration: 101.820728ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:49:57.957231Z","caller":"traceutil/trace.go:171","msg":"trace[1036957777] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"106.151393ms","start":"2026-07-16T06:49:57.851062Z","end":"2026-07-16T06:49:57.957213Z","steps":["trace[1036957777] 'process raft request' (duration: 106.010801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:01.535628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.865873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:50:01.535753Z","caller":"traceutil/trace.go:171","msg":"trace[1950258900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"578.066226ms","start":"2026-07-16T06:50:00.957663Z","end":"2026-07-16T06:50:01.535729Z","steps":["trace[1950258900] 'range keys from in-memory index tree' (duration: 577.746101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:01.5358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:00.957631Z","time spent":"578.160837ms","remote":"127.0.0.1:50710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T06:50:01.535786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.565687ms","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-07-16T06:50:01.535896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.663491ms","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-07-16T06:50:01.53593Z","caller":"traceutil/trace.go:171","msg":"trace[1501957089] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:808; }","duration":"613.73787ms","start":"2026-07-16T06:50:00.922168Z","end":"2026-07-16T06:50:01.535906Z","steps":["trace[1501957089] 'range keys from in-memory index tree' (duration: 613.540607ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:01.535974Z","caller":"traceutil/trace.go:171","msg":"trace[888318565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"910.780563ms","start":"2026-07-16T06:50:00.625176Z","end":"2026-07-16T06:50:01.535956Z","steps":["trace[888318565] 'range keys from in-memory index tree' (duration: 910.499009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:01.536014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:00.62516Z","time spent":"910.845484ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T06:50:01.535987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.23811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-16T06:50:01.53603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.224939ms","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-07-16T06:50:01.536093Z","caller":"traceutil/trace.go:171","msg":"trace[1934399430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"902.375102ms","start":"2026-07-16T06:50:00.633689Z","end":"2026-07-16T06:50:01.536065Z","steps":["trace[1934399430] 'range keys from in-memory index tree' (duration: 902.049137ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:01.536102Z","caller":"traceutil/trace.go:171","msg":"trace[2059151349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"920.29647ms","start":"2026-07-16T06:50:00.615787Z","end":"2026-07-16T06:50:01.536083Z","steps":["trace[2059151349] 'range keys from in-memory index tree' (duration: 919.985556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:01.536141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:00.633681Z","time spent":"902.450603ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T06:50:01.536154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:00.615707Z","time spent":"920.434452ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T06:50:01.575322Z","caller":"traceutil/trace.go:171","msg":"trace[1177130048] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"161.729319ms","start":"2026-07-16T06:50:01.41357Z","end":"2026-07-16T06:50:01.575299Z","steps":["trace[1177130048] 'read index received' (duration: 161.582767ms)","trace[1177130048] 'applied index is now lower than readState.Index' (duration: 145.012µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:01.575432Z","caller":"traceutil/trace.go:171","msg":"trace[1527762725] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"435.422788ms","start":"2026-07-16T06:50:01.139997Z","end":"2026-07-16T06:50:01.57542Z","steps":["trace[1527762725] 'process raft request' (duration: 435.121594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:01.575696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.108945ms","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-07-16T06:50:01.575756Z","caller":"traceutil/trace.go:171","msg":"trace[74082312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"162.207487ms","start":"2026-07-16T06:50:01.413533Z","end":"2026-07-16T06:50:01.575741Z","steps":["trace[74082312] 'agreement among raft nodes before linearized reading' (duration: 162.056625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:01.575783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:01.139974Z","time spent":"435.486239ms","remote":"127.0.0.1:51000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:50:05.348245Z","caller":"traceutil/trace.go:171","msg":"trace[1522256987] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"170.749599ms","start":"2026-07-16T06:50:05.177474Z","end":"2026-07-16T06:50:05.348223Z","steps":["trace[1522256987] 'process raft request' (duration: 150.32191ms)","trace[1522256987] 'compare' (duration: 20.327058ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:05.534942Z","caller":"traceutil/trace.go:171","msg":"trace[1125152660] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"180.024141ms","start":"2026-07-16T06:50:05.354878Z","end":"2026-07-16T06:50:05.534902Z","steps":["trace[1125152660] 'process raft request' (duration: 166.782759ms)","trace[1125152660] 'compare' (duration: 13.080709ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:06.020886Z","caller":"traceutil/trace.go:171","msg":"trace[915163199] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"182.017219ms","start":"2026-07-16T06:50:05.838844Z","end":"2026-07-16T06:50:06.020861Z","steps":["trace[915163199] 'process raft request' (duration: 181.900147ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:06.021262Z","caller":"traceutil/trace.go:171","msg":"trace[706088416] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"183.50391ms","start":"2026-07-16T06:50:05.837732Z","end":"2026-07-16T06:50:06.021236Z","steps":["trace[706088416] 'process raft request' (duration: 178.063388ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:06.191123Z","caller":"traceutil/trace.go:171","msg":"trace[1109346742] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"112.409247ms","start":"2026-07-16T06:50:06.078695Z","end":"2026-07-16T06:50:06.191104Z","steps":["trace[1109346742] 'process raft request' (duration: 112.367266ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:06.191465Z","caller":"traceutil/trace.go:171","msg":"trace[2079097614] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"115.319011ms","start":"2026-07-16T06:50:06.076113Z","end":"2026-07-16T06:50:06.191432Z","steps":["trace[2079097614] 'process raft request' (duration: 114.864364ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.428846Z","caller":"traceutil/trace.go:171","msg":"trace[452767479] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"459.704678ms","start":"2026-07-16T06:50:41.969111Z","end":"2026-07-16T06:50:42.428816Z","steps":["trace[452767479] 'process raft request' (duration: 431.730789ms)","trace[452767479] 'compare' (duration: 27.605014ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:50:42.428982Z","caller":"traceutil/trace.go:171","msg":"trace[44979713] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1120; }","duration":"437.301561ms","start":"2026-07-16T06:50:41.991644Z","end":"2026-07-16T06:50:42.428946Z","steps":["trace[44979713] 'read index received' (duration: 409.220151ms)","trace[44979713] 'applied index is now lower than readState.Index' (duration: 28.08022ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:50:42.429044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:41.969087Z","time spent":"459.846529ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T06:50:42.42926Z","caller":"traceutil/trace.go:171","msg":"trace[238358442] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"459.747468ms","start":"2026-07-16T06:50:41.969492Z","end":"2026-07-16T06:50:42.42924Z","steps":["trace[238358442] 'process raft request' (duration: 459.250022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.429408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:41.969477Z","time spent":"459.827129ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T06:50:42.429437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.902334ms","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-07-16T06:50:42.429464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.820958ms","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-07-16T06:50:42.429492Z","caller":"traceutil/trace.go:171","msg":"trace[1664381566] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1075; }","duration":"180.008215ms","start":"2026-07-16T06:50:42.24947Z","end":"2026-07-16T06:50:42.429478Z","steps":["trace[1664381566] 'agreement among raft nodes before linearized reading' (duration: 179.826803ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:50:42.429519Z","caller":"traceutil/trace.go:171","msg":"trace[1255703621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1075; }","duration":"437.876149ms","start":"2026-07-16T06:50:41.991625Z","end":"2026-07-16T06:50:42.429502Z","steps":["trace[1255703621] 'agreement among raft nodes before linearized reading' (duration: 437.704646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:50:42.429555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:50:41.991493Z","time spent":"438.054341ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T06:51:01.180189Z","caller":"traceutil/trace.go:171","msg":"trace[633820795] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"160.209411ms","start":"2026-07-16T06:51:01.019949Z","end":"2026-07-16T06:51:01.180158Z","steps":["trace[633820795] 'process raft request' (duration: 160.013889ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:04.755963Z","caller":"traceutil/trace.go:171","msg":"trace[324759476] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"127.787194ms","start":"2026-07-16T06:51:04.628141Z","end":"2026-07-16T06:51:04.755929Z","steps":["trace[324759476] 'process raft request' (duration: 125.795778ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:04.801582Z","caller":"traceutil/trace.go:171","msg":"trace[515587759] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"173.328311ms","start":"2026-07-16T06:51:04.628216Z","end":"2026-07-16T06:51:04.801544Z","steps":["trace[515587759] 'process raft request' (duration: 173.086058ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:04.801781Z","caller":"traceutil/trace.go:171","msg":"trace[1094723075] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"171.995954ms","start":"2026-07-16T06:51:04.629746Z","end":"2026-07-16T06:51:04.801742Z","steps":["trace[1094723075] 'process raft request' (duration: 171.732101ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:11.049789Z","caller":"traceutil/trace.go:171","msg":"trace[234565344] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"192.096304ms","start":"2026-07-16T06:51:10.85767Z","end":"2026-07-16T06:51:11.049766Z","steps":["trace[234565344] 'read index received' (duration: 191.905181ms)","trace[234565344] 'applied index is now lower than readState.Index' (duration: 189.543µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:51:11.050034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.896008ms","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-07-16T06:51:11.050059Z","caller":"traceutil/trace.go:171","msg":"trace[1973167113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1166; }","duration":"182.00317ms","start":"2026-07-16T06:51:10.868049Z","end":"2026-07-16T06:51:11.050052Z","steps":["trace[1973167113] 'agreement among raft nodes before linearized reading' (duration: 181.843578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:11.050094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.388708ms","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-07-16T06:51:11.050201Z","caller":"traceutil/trace.go:171","msg":"trace[1325334330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1166; }","duration":"192.56903ms","start":"2026-07-16T06:51:10.857602Z","end":"2026-07-16T06:51:11.050172Z","steps":["trace[1325334330] 'agreement among raft nodes before linearized reading' (duration: 192.267866ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:11.050211Z","caller":"traceutil/trace.go:171","msg":"trace[748751430] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"290.997941ms","start":"2026-07-16T06:51:10.759194Z","end":"2026-07-16T06:51:11.050191Z","steps":["trace[748751430] 'process raft request' (duration: 290.342272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:51:11.050393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.342375ms","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-07-16T06:51:11.050424Z","caller":"traceutil/trace.go:171","msg":"trace[737572528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1166; }","duration":"178.372795ms","start":"2026-07-16T06:51:10.872044Z","end":"2026-07-16T06:51:11.050417Z","steps":["trace[737572528] 'agreement among raft nodes before linearized reading' (duration: 178.295984ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:26.214763Z","caller":"traceutil/trace.go:171","msg":"trace[1030781430] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"293.329088ms","start":"2026-07-16T06:51:25.921399Z","end":"2026-07-16T06:51:26.214728Z","steps":["trace[1030781430] 'process raft request' (duration: 293.144286ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:26.434964Z","caller":"traceutil/trace.go:171","msg":"trace[1575667614] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"172.121338ms","start":"2026-07-16T06:51:26.262795Z","end":"2026-07-16T06:51:26.434916Z","steps":["trace[1575667614] 'process raft request' (duration: 158.616257ms)","trace[1575667614] 'compare' (duration: 13.213627ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:51:28.347913Z","caller":"traceutil/trace.go:171","msg":"trace[1618094358] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"127.002183ms","start":"2026-07-16T06:51:28.220885Z","end":"2026-07-16T06:51:28.347887Z","steps":["trace[1618094358] 'process raft request' (duration: 126.819271ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:29.060804Z","caller":"traceutil/trace.go:171","msg":"trace[958474428] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"117.980295ms","start":"2026-07-16T06:51:28.942758Z","end":"2026-07-16T06:51:29.060738Z","steps":["trace[958474428] 'read index received' (duration: 117.811223ms)","trace[958474428] 'applied index is now lower than readState.Index' (duration: 167.052µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:51:29.060952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.140706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:51:29.061002Z","caller":"traceutil/trace.go:171","msg":"trace[305230676] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1224; }","duration":"118.231978ms","start":"2026-07-16T06:51:28.942754Z","end":"2026-07-16T06:51:29.060986Z","steps":["trace[305230676] 'agreement among raft nodes before linearized reading' (duration: 118.099106ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:31.370468Z","caller":"traceutil/trace.go:171","msg":"trace[1981184235] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"104.56245ms","start":"2026-07-16T06:51:31.265865Z","end":"2026-07-16T06:51:31.370427Z","steps":["trace[1981184235] 'process raft request' (duration: 102.584487ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:31.370677Z","caller":"traceutil/trace.go:171","msg":"trace[160589437] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"104.609891ms","start":"2026-07-16T06:51:31.26603Z","end":"2026-07-16T06:51:31.37064Z","steps":["trace[160589437] 'process raft request' (duration: 104.341827ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:51:49.845833Z","caller":"traceutil/trace.go:171","msg":"trace[1492274311] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"120.566347ms","start":"2026-07-16T06:51:49.725229Z","end":"2026-07-16T06:51:49.845796Z","steps":["trace[1492274311] 'process raft request' (duration: 120.36298ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:01.736303Z","caller":"traceutil/trace.go:171","msg":"trace[950541072] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"180.348219ms","start":"2026-07-16T06:52:01.555918Z","end":"2026-07-16T06:52:01.736266Z","steps":["trace[950541072] 'process raft request' (duration: 180.145643ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:01.736698Z","caller":"traceutil/trace.go:171","msg":"trace[2079525576] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"171.669917ms","start":"2026-07-16T06:52:01.564826Z","end":"2026-07-16T06:52:01.736496Z","steps":["trace[2079525576] 'read index received' (duration: 171.131419ms)","trace[2079525576] 'applied index is now lower than readState.Index' (duration: 537.078µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:01.73683Z","caller":"traceutil/trace.go:171","msg":"trace[917392962] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"180.897857ms","start":"2026-07-16T06:52:01.555918Z","end":"2026-07-16T06:52:01.736816Z","steps":["trace[917392962] 'process raft request' (duration: 180.310078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:01.737276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.337748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-07-16T06:52:01.737877Z","caller":"traceutil/trace.go:171","msg":"trace[946791302] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1368; }","duration":"118.927017ms","start":"2026-07-16T06:52:01.618841Z","end":"2026-07-16T06:52:01.737768Z","steps":["trace[946791302] 'agreement among raft nodes before linearized reading' (duration: 118.191693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:01.737359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.510544ms","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-07-16T06:52:01.738206Z","caller":"traceutil/trace.go:171","msg":"trace[49708235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1368; }","duration":"173.432524ms","start":"2026-07-16T06:52:01.564755Z","end":"2026-07-16T06:52:01.738188Z","steps":["trace[49708235] 'agreement among raft nodes before linearized reading' (duration: 172.379459ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:03.887897Z","caller":"traceutil/trace.go:171","msg":"trace[1675958945] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1438; }","duration":"135.181397ms","start":"2026-07-16T06:52:03.752695Z","end":"2026-07-16T06:52:03.887876Z","steps":["trace[1675958945] 'read index received' (duration: 40.986154ms)","trace[1675958945] 'applied index is now lower than readState.Index' (duration: 94.194073ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T06:52:03.888072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.364482ms","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-07-16T06:52:03.888112Z","caller":"traceutil/trace.go:171","msg":"trace[2121475940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1375; }","duration":"135.445905ms","start":"2026-07-16T06:52:03.752649Z","end":"2026-07-16T06:52:03.888095Z","steps":["trace[2121475940] 'agreement among raft nodes before linearized reading' (duration: 135.307831ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:03.888458Z","caller":"traceutil/trace.go:171","msg":"trace[1657760379] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"141.694747ms","start":"2026-07-16T06:52:03.746288Z","end":"2026-07-16T06:52:03.887982Z","steps":["trace[1657760379] 'process raft request' (duration: 117.812866ms)","trace[1657760379] 'compare' (duration: 23.675955ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:04.848593Z","caller":"traceutil/trace.go:171","msg":"trace[918207516] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"107.647832ms","start":"2026-07-16T06:52:04.740914Z","end":"2026-07-16T06:52:04.848562Z","steps":["trace[918207516] 'process raft request' (duration: 107.452046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:05.590113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.776089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T06:52:05.590234Z","caller":"traceutil/trace.go:171","msg":"trace[1430987064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"136.962984ms","start":"2026-07-16T06:52:05.453241Z","end":"2026-07-16T06:52:05.590204Z","steps":["trace[1430987064] 'range keys from in-memory index tree' (duration: 136.461508ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:05.759368Z","caller":"traceutil/trace.go:171","msg":"trace[131909240] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"163.521023ms","start":"2026-07-16T06:52:05.595806Z","end":"2026-07-16T06:52:05.759327Z","steps":["trace[131909240] 'process raft request' (duration: 163.358438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:06.157203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.820605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T06:52:06.157266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.586086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:06.157305Z","caller":"traceutil/trace.go:171","msg":"trace[579918136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1382; }","duration":"199.996861ms","start":"2026-07-16T06:52:05.957286Z","end":"2026-07-16T06:52:06.157282Z","steps":["trace[579918136] 'range keys from in-memory index tree' (duration: 199.715522ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:06.157333Z","caller":"traceutil/trace.go:171","msg":"trace[1782147585] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1382; }","duration":"158.682999ms","start":"2026-07-16T06:52:05.998626Z","end":"2026-07-16T06:52:06.157309Z","steps":["trace[1782147585] 'range keys from in-memory index tree' (duration: 158.484362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:06.157439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.169605ms","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-07-16T06:52:06.157537Z","caller":"traceutil/trace.go:171","msg":"trace[1358119013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1382; }","duration":"264.378532ms","start":"2026-07-16T06:52:05.893138Z","end":"2026-07-16T06:52:06.157516Z","steps":["trace[1358119013] 'range keys from in-memory index tree' (duration: 263.856685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:06.157539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.827636ms","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-07-16T06:52:06.157644Z","caller":"traceutil/trace.go:171","msg":"trace[925354231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1382; }","duration":"359.003062ms","start":"2026-07-16T06:52:05.798618Z","end":"2026-07-16T06:52:06.157621Z","steps":["trace[925354231] 'range keys from in-memory index tree' (duration: 358.549707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:06.157698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T06:52:05.798582Z","time spent":"359.104595ms","remote":"127.0.0.1:51020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T06:52:06.15716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.089776ms","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-07-16T06:52:06.158066Z","caller":"traceutil/trace.go:171","msg":"trace[1331690075] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"237.063657ms","start":"2026-07-16T06:52:05.920984Z","end":"2026-07-16T06:52:06.158048Z","steps":["trace[1331690075] 'range keys from in-memory index tree' (duration: 235.821518ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:06.263387Z","caller":"traceutil/trace.go:171","msg":"trace[1973044488] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"100.08688ms","start":"2026-07-16T06:52:06.163262Z","end":"2026-07-16T06:52:06.263349Z","steps":["trace[1973044488] 'process raft request' (duration: 99.853883ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:06.350183Z","caller":"traceutil/trace.go:171","msg":"trace[1656920965] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"185.070098ms","start":"2026-07-16T06:52:06.165073Z","end":"2026-07-16T06:52:06.350143Z","steps":["trace[1656920965] 'process raft request' (duration: 184.832391ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:06.350371Z","caller":"traceutil/trace.go:171","msg":"trace[1315594293] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"185.241733ms","start":"2026-07-16T06:52:06.16509Z","end":"2026-07-16T06:52:06.350332Z","steps":["trace[1315594293] 'process raft request' (duration: 185.009456ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:06.941846Z","caller":"traceutil/trace.go:171","msg":"trace[1981692426] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"289.507618ms","start":"2026-07-16T06:52:06.652292Z","end":"2026-07-16T06:52:06.9418Z","steps":["trace[1981692426] 'process raft request' (duration: 289.23736ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:07.165386Z","caller":"traceutil/trace.go:171","msg":"trace[347158622] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1452; }","duration":"209.97301ms","start":"2026-07-16T06:52:06.955382Z","end":"2026-07-16T06:52:07.165355Z","steps":["trace[347158622] 'read index received' (duration: 209.867546ms)","trace[347158622] 'applied index is now lower than readState.Index' (duration: 103.813µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:07.165478Z","caller":"traceutil/trace.go:171","msg":"trace[1970037976] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"218.906304ms","start":"2026-07-16T06:52:06.946523Z","end":"2026-07-16T06:52:07.165429Z","steps":["trace[1970037976] 'process raft request' (duration: 218.654156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:07.1657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.292039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T06:52:07.165745Z","caller":"traceutil/trace.go:171","msg":"trace[1524327955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1387; }","duration":"210.386502ms","start":"2026-07-16T06:52:06.955346Z","end":"2026-07-16T06:52:07.165732Z","steps":["trace[1524327955] 'agreement among raft nodes before linearized reading' (duration: 210.117974ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:07.880318Z","caller":"traceutil/trace.go:171","msg":"trace[2074872548] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"110.473574ms","start":"2026-07-16T06:52:07.769807Z","end":"2026-07-16T06:52:07.880281Z","steps":["trace[2074872548] 'process raft request' (duration: 110.286268ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T06:52:24.124093Z","caller":"traceutil/trace.go:171","msg":"trace[1134690803] linearizableReadLoop","detail":"{readStateIndex:1514; appliedIndex:1513; }","duration":"110.672161ms","start":"2026-07-16T06:52:24.013391Z","end":"2026-07-16T06:52:24.124063Z","steps":["trace[1134690803] 'read index received' (duration: 110.502116ms)","trace[1134690803] 'applied index is now lower than readState.Index' (duration: 168.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T06:52:24.124193Z","caller":"traceutil/trace.go:171","msg":"trace[1549805748] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"159.119813ms","start":"2026-07-16T06:52:23.965041Z","end":"2026-07-16T06:52:24.12416Z","steps":["trace[1549805748] 'process raft request' (duration: 158.853625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T06:52:24.124379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.940849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-public\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T06:52:24.124481Z","caller":"traceutil/trace.go:171","msg":"trace[2129611561] range","detail":"{range_begin:/registry/namespaces/kube-public; range_end:; response_count:1; response_revision:1445; }","duration":"111.098213ms","start":"2026-07-16T06:52:24.013363Z","end":"2026-07-16T06:52:24.124461Z","steps":["trace[2129611561] 'agreement among raft nodes before linearized reading' (duration: 110.843035ms)"],"step_count":1}