{"level":"warn","ts":"2026-02-11T01:43:37.440713Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T01:43:37.440976Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-11T01:43:37.441125Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T01:43:37.441159Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-11T01:43:37.441203Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-11T01:43:37.441762Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-11T01:43:37.441904Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-11T01:43:37.451017Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.666524ms"} {"level":"info","ts":"2026-02-11T01:43:37.459289Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-11T01:43:37.459375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:43:37.459412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-11T01:43:37.459449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T01:43:37.459467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-11T01:43:37.459533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-11T01:43:37.467777Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:43:37.471209Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:43:37.477161Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-11T01:43:37.483379Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T01:43:37.492529Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-11T01:43:37.500239Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-11T01:43:37.512562Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-11T01:43:37.513257Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-11T01:43:37.513352Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-11T01:43:37.513418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-11T01:43:37.513548Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T01:43:37.513834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-11T01:43:37.514101Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T01:43:37.514418Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:43:37.521347Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-11T01:43:38.460445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:43:38.460487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:43:38.460512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-11T01:43:38.460523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:43:38.460528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-11T01:43:38.460536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-11T01:43:38.460542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-11T01:43:38.461549Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:43:38.463985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T01:43:38.464012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:43:38.464373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:43:38.464526Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:43:38.464597Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:43:38.464617Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:43:38.468173Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T01:43:38.475609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-11T01:43:38.492241Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:43:38.492274Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:43:40.369878Z","caller":"traceutil/trace.go:171","msg":"trace[751972503] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"314.401539ms","start":"2026-02-11T01:43:40.055458Z","end":"2026-02-11T01:43:40.369859Z","steps":["trace[751972503] 'process raft request' (duration: 314.274298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:40.370753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.055449Z","time spent":"314.55248ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:43:41.028048Z","caller":"traceutil/trace.go:171","msg":"trace[2078048766] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"935.52852ms","start":"2026-02-11T01:43:40.092263Z","end":"2026-02-11T01:43:41.027791Z","steps":["trace[2078048766] 'process raft request' (duration: 935.289199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.028589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.092243Z","time spent":"935.903074ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:43:41.028989Z","caller":"traceutil/trace.go:171","msg":"trace[605824129] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"961.308806ms","start":"2026-02-11T01:43:40.067628Z","end":"2026-02-11T01:43:41.028936Z","steps":["trace[605824129] 'read index received' (duration: 303.887233ms)","trace[605824129] 'applied index is now lower than readState.Index' (duration: 657.419173ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:41.029887Z","caller":"traceutil/trace.go:171","msg":"trace[1647412179] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"937.353354ms","start":"2026-02-11T01:43:40.092383Z","end":"2026-02-11T01:43:41.029736Z","steps":["trace[1647412179] 'process raft request' (duration: 935.239999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.030064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.092377Z","time spent":"937.650187ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:43:41.030077Z","caller":"traceutil/trace.go:171","msg":"trace[766905537] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"974.159539ms","start":"2026-02-11T01:43:40.055616Z","end":"2026-02-11T01:43:41.029775Z","steps":["trace[766905537] 'process raft request' (duration: 971.101417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.030461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.055583Z","time spent":"974.687223ms","remote":"127.0.0.1:39944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:43:41.031326Z","caller":"traceutil/trace.go:171","msg":"trace[359319108] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"938.874164ms","start":"2026-02-11T01:43:40.092426Z","end":"2026-02-11T01:43:41.0313Z","steps":["trace[359319108] 'process raft request' (duration: 935.252929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.031587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.092422Z","time spent":"938.973455ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:43:41.031938Z","caller":"traceutil/trace.go:171","msg":"trace[2111803525] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"939.436518ms","start":"2026-02-11T01:43:40.092457Z","end":"2026-02-11T01:43:41.031894Z","steps":["trace[2111803525] 'process raft request' (duration: 935.253429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.032044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.092454Z","time spent":"939.508839ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:43:41.033117Z","caller":"traceutil/trace.go:171","msg":"trace[1908525265] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"929.395506ms","start":"2026-02-11T01:43:40.103708Z","end":"2026-02-11T01:43:41.033103Z","steps":["trace[1908525265] 'process raft request' (duration: 924.35801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.033261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.103694Z","time spent":"929.446327ms","remote":"127.0.0.1:40122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:43:41.034076Z","caller":"traceutil/trace.go:171","msg":"trace[995973821] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"941.345473ms","start":"2026-02-11T01:43:40.092655Z","end":"2026-02-11T01:43:41.034001Z","steps":["trace[995973821] 'process raft request' (duration: 935.111758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.034168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.092634Z","time spent":"941.468503ms","remote":"127.0.0.1:40336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:43:41.034889Z","caller":"traceutil/trace.go:171","msg":"trace[1077844977] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"940.675107ms","start":"2026-02-11T01:43:40.094174Z","end":"2026-02-11T01:43:41.034849Z","steps":["trace[1077844977] 'process raft request' (duration: 933.731118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.035024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.094168Z","time spent":"940.750448ms","remote":"127.0.0.1:40038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2846,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:43:41.036008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.247646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.036134Z","caller":"traceutil/trace.go:171","msg":"trace[888210078] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"968.301727ms","start":"2026-02-11T01:43:40.067735Z","end":"2026-02-11T01:43:41.036037Z","steps":["trace[888210078] 'agreement among raft nodes before linearized reading' (duration: 968.112046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.03624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.067728Z","time spent":"968.444878ms","remote":"127.0.0.1:39978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-11T01:43:41.037974Z","caller":"traceutil/trace.go:171","msg":"trace[1794568510] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"926.565864ms","start":"2026-02-11T01:43:40.111383Z","end":"2026-02-11T01:43:41.037949Z","steps":["trace[1794568510] 'process raft request' (duration: 917.008316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.038029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.111369Z","time spent":"926.640115ms","remote":"127.0.0.1:40408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:43:41.038324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.691923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.038421Z","caller":"traceutil/trace.go:171","msg":"trace[171415681] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:0; response_revision:11; }","duration":"970.771874ms","start":"2026-02-11T01:43:40.067571Z","end":"2026-02-11T01:43:41.038343Z","steps":["trace[171415681] 'agreement among raft nodes before linearized reading' (duration: 970.674153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.038507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.067556Z","time spent":"970.885766ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-11T01:43:41.043288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.138868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.0434Z","caller":"traceutil/trace.go:171","msg":"trace[1786123139] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"902.238299ms","start":"2026-02-11T01:43:40.141091Z","end":"2026-02-11T01:43:41.04333Z","steps":["trace[1786123139] 'agreement among raft nodes before linearized reading' (duration: 900.024183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.043494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.141081Z","time spent":"902.40319ms","remote":"127.0.0.1:39978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-11T01:43:41.045661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.169714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.045688Z","caller":"traceutil/trace.go:171","msg":"trace[501963744] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"936.210624ms","start":"2026-02-11T01:43:40.10947Z","end":"2026-02-11T01:43:41.045681Z","steps":["trace[501963744] 'agreement among raft nodes before linearized reading' (duration: 936.166834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.045821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.109464Z","time spent":"936.307814ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-11T01:43:41.046043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.360912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.046063Z","caller":"traceutil/trace.go:171","msg":"trace[1058864945] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"937.392852ms","start":"2026-02-11T01:43:40.108665Z","end":"2026-02-11T01:43:41.046057Z","steps":["trace[1058864945] 'agreement among raft nodes before linearized reading' (duration: 937.359292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.046136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.108661Z","time spent":"937.469463ms","remote":"127.0.0.1:39978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-11T01:43:41.046352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.657514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.046378Z","caller":"traceutil/trace.go:171","msg":"trace[678508351] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"937.735435ms","start":"2026-02-11T01:43:40.108635Z","end":"2026-02-11T01:43:41.046371Z","steps":["trace[678508351] 'agreement among raft nodes before linearized reading' (duration: 937.642414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.046415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.108628Z","time spent":"937.780995ms","remote":"127.0.0.1:39978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-11T01:43:41.046526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.634931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.046548Z","caller":"traceutil/trace.go:171","msg":"trace[1342783116] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"942.681171ms","start":"2026-02-11T01:43:40.103861Z","end":"2026-02-11T01:43:41.046542Z","steps":["trace[1342783116] 'agreement among raft nodes before linearized reading' (duration: 942.64382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.046586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.103855Z","time spent":"942.726891ms","remote":"127.0.0.1:39978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-11T01:43:41.046687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.654603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.046722Z","caller":"traceutil/trace.go:171","msg":"trace[388144979] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"952.703083ms","start":"2026-02-11T01:43:40.093998Z","end":"2026-02-11T01:43:41.046701Z","steps":["trace[388144979] 'agreement among raft nodes before linearized reading' (duration: 952.664433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.046744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:40.093989Z","time spent":"952.746164ms","remote":"127.0.0.1:39948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-02-11T01:43:41.21132Z","caller":"traceutil/trace.go:171","msg":"trace[1524086201] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"134.744655ms","start":"2026-02-11T01:43:41.076548Z","end":"2026-02-11T01:43:41.211292Z","steps":["trace[1524086201] 'process raft request' (duration: 134.524114ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:41.21167Z","caller":"traceutil/trace.go:171","msg":"trace[1544200084] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:34; }","duration":"132.868861ms","start":"2026-02-11T01:43:41.078681Z","end":"2026-02-11T01:43:41.21155Z","steps":["trace[1544200084] 'read index received' (duration: 86.510479ms)","trace[1544200084] 'applied index is now lower than readState.Index' (duration: 46.355852ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:41.212129Z","caller":"traceutil/trace.go:171","msg":"trace[510461577] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"135.885163ms","start":"2026-02-11T01:43:41.076226Z","end":"2026-02-11T01:43:41.212111Z","steps":["trace[510461577] 'process raft request' (duration: 88.889237ms)","trace[510461577] 'compare' (duration: 45.773667ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:41.21281Z","caller":"traceutil/trace.go:171","msg":"trace[277765443] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"134.262881ms","start":"2026-02-11T01:43:41.078533Z","end":"2026-02-11T01:43:41.212796Z","steps":["trace[277765443] 'process raft request' (duration: 132.69656ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:41.213147Z","caller":"traceutil/trace.go:171","msg":"trace[1013406987] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"134.672935ms","start":"2026-02-11T01:43:41.078463Z","end":"2026-02-11T01:43:41.213136Z","steps":["trace[1013406987] 'process raft request' (duration: 132.67777ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:41.213515Z","caller":"traceutil/trace.go:171","msg":"trace[901901659] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"135.009257ms","start":"2026-02-11T01:43:41.078493Z","end":"2026-02-11T01:43:41.213502Z","steps":["trace[901901659] 'process raft request' (duration: 132.67494ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:41.214186Z","caller":"traceutil/trace.go:171","msg":"trace[2068558521] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"135.550821ms","start":"2026-02-11T01:43:41.078622Z","end":"2026-02-11T01:43:41.214173Z","steps":["trace[2068558521] 'process raft request' (duration: 132.70585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:41.214537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.793712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-11T01:43:41.214671Z","caller":"traceutil/trace.go:171","msg":"trace[2101114413] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:39; }","duration":"135.964943ms","start":"2026-02-11T01:43:41.078671Z","end":"2026-02-11T01:43:41.214636Z","steps":["trace[2101114413] 'agreement among raft nodes before linearized reading' (duration: 133.218113ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:41.21473Z","caller":"traceutil/trace.go:171","msg":"trace[1466039248] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"136.115644ms","start":"2026-02-11T01:43:41.078602Z","end":"2026-02-11T01:43:41.214718Z","steps":["trace[1466039248] 'process raft request' (duration: 132.70381ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:41.215266Z","caller":"traceutil/trace.go:171","msg":"trace[1270192183] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"136.464898ms","start":"2026-02-11T01:43:41.078787Z","end":"2026-02-11T01:43:41.215252Z","steps":["trace[1270192183] 'process raft request' (duration: 132.58994ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:41.214513Z","caller":"traceutil/trace.go:171","msg":"trace[1311121176] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"135.926233ms","start":"2026-02-11T01:43:41.078575Z","end":"2026-02-11T01:43:41.214502Z","steps":["trace[1311121176] 'process raft request' (duration: 132.68053ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:45.013052Z","caller":"traceutil/trace.go:171","msg":"trace[2001649412] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"104.15448ms","start":"2026-02-11T01:43:44.908874Z","end":"2026-02-11T01:43:45.013028Z","steps":["trace[2001649412] 'process raft request' (duration: 104.07161ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:45.444494Z","caller":"traceutil/trace.go:171","msg":"trace[1650324716] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:310; }","duration":"516.923133ms","start":"2026-02-11T01:43:44.927555Z","end":"2026-02-11T01:43:45.444478Z","steps":["trace[1650324716] 'read index received' (duration: 85.394628ms)","trace[1650324716] 'applied index is now lower than readState.Index' (duration: 431.527865ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:45.444565Z","caller":"traceutil/trace.go:171","msg":"trace[1907314255] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"524.109934ms","start":"2026-02-11T01:43:44.92045Z","end":"2026-02-11T01:43:45.444559Z","steps":["trace[1907314255] 'process raft request' (duration: 523.983774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:45.44463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:44.920438Z","time spent":"524.141555ms","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:43:45.444718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.160914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:43:45.444734Z","caller":"traceutil/trace.go:171","msg":"trace[952815189] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:303; }","duration":"517.194116ms","start":"2026-02-11T01:43:44.927535Z","end":"2026-02-11T01:43:45.444729Z","steps":["trace[952815189] 'agreement among raft nodes before linearized reading' (duration: 517.164625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:45.444747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:44.927529Z","time spent":"517.215046ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"warn","ts":"2026-02-11T01:43:45.444883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.113804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-11T01:43:45.444899Z","caller":"traceutil/trace.go:171","msg":"trace[568635897] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:303; }","duration":"467.151405ms","start":"2026-02-11T01:43:44.977743Z","end":"2026-02-11T01:43:45.444895Z","steps":["trace[568635897] 'agreement among raft nodes before linearized reading' (duration: 467.123425ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:45.444868Z","caller":"traceutil/trace.go:171","msg":"trace[715667682] transaction","detail":"{read_only:false; number_of_response:0; response_revision:302; }","duration":"535.752375ms","start":"2026-02-11T01:43:44.909078Z","end":"2026-02-11T01:43:45.444831Z","steps":["trace[715667682] 'process raft request' (duration: 535.302592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:45.444914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:44.97773Z","time spent":"467.180535ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"warn","ts":"2026-02-11T01:43:45.444974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:44.909052Z","time spent":"535.854687ms","remote":"127.0.0.1:40050","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-02-11T01:43:45.444995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.664905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:43:45.445011Z","caller":"traceutil/trace.go:171","msg":"trace[2000608619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:303; }","duration":"181.715535ms","start":"2026-02-11T01:43:45.26329Z","end":"2026-02-11T01:43:45.445006Z","steps":["trace[2000608619] 'agreement among raft nodes before linearized reading' (duration: 181.692385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:45.445089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.838631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-11T01:43:45.445101Z","caller":"traceutil/trace.go:171","msg":"trace[1542494052] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:303; }","duration":"430.874921ms","start":"2026-02-11T01:43:45.014222Z","end":"2026-02-11T01:43:45.445097Z","steps":["trace[1542494052] 'agreement among raft nodes before linearized reading' (duration: 430.845471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:45.445112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:45.014208Z","time spent":"430.901101ms","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5515,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-11T01:43:45.600822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.235722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:43:45.600994Z","caller":"traceutil/trace.go:171","msg":"trace[201964894] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"145.580864ms","start":"2026-02-11T01:43:45.45539Z","end":"2026-02-11T01:43:45.600971Z","steps":["trace[201964894] 'process raft request' (duration: 29.935539ms)","trace[201964894] 'compare' (duration: 115.069461ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:45.601196Z","caller":"traceutil/trace.go:171","msg":"trace[992007934] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"143.911782ms","start":"2026-02-11T01:43:45.457275Z","end":"2026-02-11T01:43:45.601187Z","steps":["trace[992007934] 'read index received' (duration: 28.058316ms)","trace[992007934] 'applied index is now lower than readState.Index' (duration: 115.852466ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:45.601223Z","caller":"traceutil/trace.go:171","msg":"trace[25613384] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"141.795706ms","start":"2026-02-11T01:43:45.459409Z","end":"2026-02-11T01:43:45.601205Z","steps":["trace[25613384] 'process raft request' (duration: 141.710906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:45.601303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.014953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-11T01:43:45.601321Z","caller":"traceutil/trace.go:171","msg":"trace[1593264529] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:305; }","duration":"144.062153ms","start":"2026-02-11T01:43:45.457254Z","end":"2026-02-11T01:43:45.601316Z","steps":["trace[1593264529] 'agreement among raft nodes before linearized reading' (duration: 143.982833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:45.601431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.188329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-11T01:43:45.601444Z","caller":"traceutil/trace.go:171","msg":"trace[265495729] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:305; }","duration":"142.23589ms","start":"2026-02-11T01:43:45.459204Z","end":"2026-02-11T01:43:45.60144Z","steps":["trace[265495729] 'agreement among raft nodes before linearized reading' (duration: 142.20708ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:48.176583Z","caller":"traceutil/trace.go:171","msg":"trace[1466980683] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"148.302813ms","start":"2026-02-11T01:43:48.028261Z","end":"2026-02-11T01:43:48.176564Z","steps":["trace[1466980683] 'process raft request' (duration: 148.195672ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:48.548525Z","caller":"traceutil/trace.go:171","msg":"trace[1236990277] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"190.629612ms","start":"2026-02-11T01:43:48.357863Z","end":"2026-02-11T01:43:48.548493Z","steps":["trace[1236990277] 'read index received' (duration: 185.000053ms)","trace[1236990277] 'applied index is now lower than readState.Index' (duration: 5.628449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:43:48.549083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.190417ms","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-02-11T01:43:48.549289Z","caller":"traceutil/trace.go:171","msg":"trace[148631115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:335; }","duration":"191.306517ms","start":"2026-02-11T01:43:48.357815Z","end":"2026-02-11T01:43:48.549122Z","steps":["trace[148631115] 'agreement among raft nodes before linearized reading' (duration: 191.084356ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:48.550072Z","caller":"traceutil/trace.go:171","msg":"trace[113413456] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"321.484916ms","start":"2026-02-11T01:43:48.228571Z","end":"2026-02-11T01:43:48.550056Z","steps":["trace[113413456] 'process raft request' (duration: 314.372357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:48.551746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:48.228546Z","time spent":"323.138558ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:43:48.552242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.869365ms","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-02-11T01:43:48.552272Z","caller":"traceutil/trace.go:171","msg":"trace[1664820615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:335; }","duration":"133.929515ms","start":"2026-02-11T01:43:48.418335Z","end":"2026-02-11T01:43:48.552265Z","steps":["trace[1664820615] 'agreement among raft nodes before linearized reading' (duration: 133.876355ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:52.934591Z","caller":"traceutil/trace.go:171","msg":"trace[498924944] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"270.956198ms","start":"2026-02-11T01:43:52.663607Z","end":"2026-02-11T01:43:52.934563Z","steps":["trace[498924944] 'process raft request' (duration: 209.8776ms)","trace[498924944] 'compare' (duration: 60.498164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:43:53.477641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529095199684215,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:43:53.658547Z","caller":"traceutil/trace.go:171","msg":"trace[220742939] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:415; }","duration":"681.118031ms","start":"2026-02-11T01:43:52.977407Z","end":"2026-02-11T01:43:53.658526Z","steps":["trace[220742939] 'read index received' (duration: 680.952269ms)","trace[220742939] 'applied index is now lower than readState.Index' (duration: 163.082µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:53.658814Z","caller":"traceutil/trace.go:171","msg":"trace[1665433827] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"719.9207ms","start":"2026-02-11T01:43:52.938883Z","end":"2026-02-11T01:43:53.658803Z","steps":["trace[1665433827] 'process raft request' (duration: 719.531797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:53.65891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:52.938869Z","time spent":"719.97209ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:43:53.659075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.667365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-11T01:43:53.659109Z","caller":"traceutil/trace.go:171","msg":"trace[471053900] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:404; }","duration":"681.753976ms","start":"2026-02-11T01:43:52.977344Z","end":"2026-02-11T01:43:53.659098Z","steps":["trace[471053900] 'agreement among raft nodes before linearized reading' (duration: 681.698186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:53.659141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:52.977327Z","time spent":"681.804396ms","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4280,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-11T01:43:53.659288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.421871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:43:53.659312Z","caller":"traceutil/trace.go:171","msg":"trace[2000917658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"479.792024ms","start":"2026-02-11T01:43:53.179512Z","end":"2026-02-11T01:43:53.659304Z","steps":["trace[2000917658] 'agreement among raft nodes before linearized reading' (duration: 479.757944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:53.659335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:43:53.179492Z","time spent":"479.837614ms","remote":"127.0.0.1:39856","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-02-11T01:43:53.918256Z","caller":"traceutil/trace.go:171","msg":"trace[114041150] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"197.43207ms","start":"2026-02-11T01:43:53.720808Z","end":"2026-02-11T01:43:53.91824Z","steps":["trace[114041150] 'read index received' (duration: 197.251559ms)","trace[114041150] 'applied index is now lower than readState.Index' (duration: 179.811µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:53.918332Z","caller":"traceutil/trace.go:171","msg":"trace[947117589] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"252.216093ms","start":"2026-02-11T01:43:53.666109Z","end":"2026-02-11T01:43:53.918325Z","steps":["trace[947117589] 'process raft request' (duration: 251.993102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:53.918477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.348477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:43:53.918525Z","caller":"traceutil/trace.go:171","msg":"trace[1779911326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"141.447369ms","start":"2026-02-11T01:43:53.777065Z","end":"2026-02-11T01:43:53.918513Z","steps":["trace[1779911326] 'agreement among raft nodes before linearized reading' (duration: 141.369418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:53.91866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.841462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-11T01:43:53.918702Z","caller":"traceutil/trace.go:171","msg":"trace[532289986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"197.931053ms","start":"2026-02-11T01:43:53.720762Z","end":"2026-02-11T01:43:53.918693Z","steps":["trace[532289986] 'agreement among raft nodes before linearized reading' (duration: 197.858282ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:54.160383Z","caller":"traceutil/trace.go:171","msg":"trace[522194082] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"235.088125ms","start":"2026-02-11T01:43:53.925286Z","end":"2026-02-11T01:43:54.160374Z","steps":["trace[522194082] 'process raft request' (duration: 208.467779ms)","trace[522194082] 'compare' (duration: 25.691909ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:54.160335Z","caller":"traceutil/trace.go:171","msg":"trace[783827988] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"234.857604ms","start":"2026-02-11T01:43:53.925462Z","end":"2026-02-11T01:43:54.16032Z","steps":["trace[783827988] 'read index received' (duration: 208.299978ms)","trace[783827988] 'applied index is now lower than readState.Index' (duration: 26.556956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:43:54.160619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.145035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-11T01:43:54.160638Z","caller":"traceutil/trace.go:171","msg":"trace[665164859] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:406; }","duration":"235.191295ms","start":"2026-02-11T01:43:53.925441Z","end":"2026-02-11T01:43:54.160632Z","steps":["trace[665164859] 'agreement among raft nodes before linearized reading' (duration: 235.115715ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:54.232978Z","caller":"traceutil/trace.go:171","msg":"trace[1664980515] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"195.920573ms","start":"2026-02-11T01:43:54.03704Z","end":"2026-02-11T01:43:54.23296Z","steps":["trace[1664980515] 'process raft request' (duration: 195.837483ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:54.352625Z","caller":"traceutil/trace.go:171","msg":"trace[2034351098] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"183.961763ms","start":"2026-02-11T01:43:54.168646Z","end":"2026-02-11T01:43:54.352608Z","steps":["trace[2034351098] 'process raft request' (duration: 163.990981ms)","trace[2034351098] 'compare' (duration: 19.904672ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:54.496851Z","caller":"traceutil/trace.go:171","msg":"trace[2144755478] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"138.609875ms","start":"2026-02-11T01:43:54.35822Z","end":"2026-02-11T01:43:54.496829Z","steps":["trace[2144755478] 'process raft request' (duration: 110.900703ms)","trace[2144755478] 'compare' (duration: 27.625732ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:54.670779Z","caller":"traceutil/trace.go:171","msg":"trace[1414651325] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"100.492283ms","start":"2026-02-11T01:43:54.570258Z","end":"2026-02-11T01:43:54.67075Z","steps":["trace[1414651325] 'process raft request' (duration: 89.110368ms)","trace[1414651325] 'compare' (duration: 10.146656ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:58.487806Z","caller":"traceutil/trace.go:171","msg":"trace[1059749727] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"161.296242ms","start":"2026-02-11T01:43:58.326486Z","end":"2026-02-11T01:43:58.487782Z","steps":["trace[1059749727] 'process raft request' (duration: 161.165612ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:58.630174Z","caller":"traceutil/trace.go:171","msg":"trace[149566820] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"135.580756ms","start":"2026-02-11T01:43:58.494573Z","end":"2026-02-11T01:43:58.630153Z","steps":["trace[149566820] 'process raft request' (duration: 85.873016ms)","trace[149566820] 'compare' (duration: 49.6126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:43:58.84489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.995329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:43:58.844965Z","caller":"traceutil/trace.go:171","msg":"trace[1664877468] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"184.401173ms","start":"2026-02-11T01:43:58.660551Z","end":"2026-02-11T01:43:58.844952Z","steps":["trace[1664877468] 'process raft request' (duration: 73.286644ms)","trace[1664877468] 'compare' (duration: 110.900958ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:58.845564Z","caller":"traceutil/trace.go:171","msg":"trace[1705831182] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:438; }","duration":"145.168419ms","start":"2026-02-11T01:43:58.700388Z","end":"2026-02-11T01:43:58.845556Z","steps":["trace[1705831182] 'read index received' (duration: 33.455666ms)","trace[1705831182] 'applied index is now lower than readState.Index' (duration: 111.712183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:43:58.845752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.36054ms","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-02-11T01:43:58.845779Z","caller":"traceutil/trace.go:171","msg":"trace[726121672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"145.38995ms","start":"2026-02-11T01:43:58.700382Z","end":"2026-02-11T01:43:58.845772Z","steps":["trace[726121672] 'agreement among raft nodes before linearized reading' (duration: 145.209339ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:58.847783Z","caller":"traceutil/trace.go:171","msg":"trace[1889128826] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"185.627481ms","start":"2026-02-11T01:43:58.662146Z","end":"2026-02-11T01:43:58.847774Z","steps":["trace[1889128826] 'process raft request' (duration: 183.296085ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:58.847996Z","caller":"traceutil/trace.go:171","msg":"trace[264500300] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"183.240035ms","start":"2026-02-11T01:43:58.664748Z","end":"2026-02-11T01:43:58.847988Z","steps":["trace[264500300] 'process raft request' (duration: 180.735719ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:58.848613Z","caller":"traceutil/trace.go:171","msg":"trace[152203456] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"183.794439ms","start":"2026-02-11T01:43:58.664792Z","end":"2026-02-11T01:43:58.848587Z","steps":["trace[152203456] 'process raft request' (duration: 180.718099ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:58.84942Z","caller":"traceutil/trace.go:171","msg":"trace[343475854] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"184.590765ms","start":"2026-02-11T01:43:58.664821Z","end":"2026-02-11T01:43:58.849412Z","steps":["trace[343475854] 'process raft request' (duration: 180.708179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:43:59.275405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.95668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:43:59.275575Z","caller":"traceutil/trace.go:171","msg":"trace[1331694540] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"271.618469ms","start":"2026-02-11T01:43:59.003937Z","end":"2026-02-11T01:43:59.275555Z","steps":["trace[1331694540] 'process raft request' (duration: 125.444967ms)","trace[1331694540] 'compare' (duration: 145.601297ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:43:59.276163Z","caller":"traceutil/trace.go:171","msg":"trace[215402969] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"265.965702ms","start":"2026-02-11T01:43:59.010184Z","end":"2026-02-11T01:43:59.27615Z","steps":["trace[215402969] 'process raft request' (duration: 265.929332ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:59.276416Z","caller":"traceutil/trace.go:171","msg":"trace[1431271255] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"272.193923ms","start":"2026-02-11T01:43:59.004212Z","end":"2026-02-11T01:43:59.276406Z","steps":["trace[1431271255] 'process raft request' (duration: 271.298977ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:59.276601Z","caller":"traceutil/trace.go:171","msg":"trace[932591372] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"269.674326ms","start":"2026-02-11T01:43:59.006918Z","end":"2026-02-11T01:43:59.276593Z","steps":["trace[932591372] 'process raft request' (duration: 269.078482ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:59.276773Z","caller":"traceutil/trace.go:171","msg":"trace[1522736365] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"269.791596ms","start":"2026-02-11T01:43:59.006973Z","end":"2026-02-11T01:43:59.276765Z","steps":["trace[1522736365] 'process raft request' (duration: 269.074202ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:43:59.276888Z","caller":"traceutil/trace.go:171","msg":"trace[1040998031] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"267.083519ms","start":"2026-02-11T01:43:59.009784Z","end":"2026-02-11T01:43:59.276867Z","steps":["trace[1040998031] 'process raft request' (duration: 266.302334ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:01.401551Z","caller":"traceutil/trace.go:171","msg":"trace[849647605] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"105.742325ms","start":"2026-02-11T01:44:01.295779Z","end":"2026-02-11T01:44:01.401521Z","steps":["trace[849647605] 'process raft request' (duration: 105.612264ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:05.120143Z","caller":"traceutil/trace.go:171","msg":"trace[1007561804] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"184.263168ms","start":"2026-02-11T01:44:04.935863Z","end":"2026-02-11T01:44:05.120126Z","steps":["trace[1007561804] 'process raft request' (duration: 184.163648ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:10.549931Z","caller":"traceutil/trace.go:171","msg":"trace[1637370668] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"225.632878ms","start":"2026-02-11T01:44:10.324279Z","end":"2026-02-11T01:44:10.549912Z","steps":["trace[1637370668] 'process raft request' (duration: 208.716485ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:10.550487Z","caller":"traceutil/trace.go:171","msg":"trace[1768261269] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"244.773583ms","start":"2026-02-11T01:44:10.305701Z","end":"2026-02-11T01:44:10.550474Z","steps":["trace[1768261269] 'process raft request' (duration: 151.115195ms)","trace[1768261269] 'compare' (duration: 75.949269ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:10.551142Z","caller":"traceutil/trace.go:171","msg":"trace[975283491] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"244.033729ms","start":"2026-02-11T01:44:10.307102Z","end":"2026-02-11T01:44:10.551135Z","steps":["trace[975283491] 'process raft request' (duration: 225.837377ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:10.552048Z","caller":"traceutil/trace.go:171","msg":"trace[828092226] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"245.897615ms","start":"2026-02-11T01:44:10.306128Z","end":"2026-02-11T01:44:10.552026Z","steps":["trace[828092226] 'read index received' (duration: 44.567386ms)","trace[828092226] 'applied index is now lower than readState.Index' (duration: 201.329719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:10.552153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.01362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14247"} {"level":"info","ts":"2026-02-11T01:44:10.552739Z","caller":"traceutil/trace.go:171","msg":"trace[1048299634] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:503; }","duration":"246.618178ms","start":"2026-02-11T01:44:10.306112Z","end":"2026-02-11T01:44:10.55273Z","steps":["trace[1048299634] 'agreement among raft nodes before linearized reading' (duration: 245.956478ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:10.554863Z","caller":"traceutil/trace.go:171","msg":"trace[1947389655] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"213.519415ms","start":"2026-02-11T01:44:10.341334Z","end":"2026-02-11T01:44:10.554854Z","steps":["trace[1947389655] 'process raft request' (duration: 210.208014ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:10.555361Z","caller":"traceutil/trace.go:171","msg":"trace[1390517808] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"198.765752ms","start":"2026-02-11T01:44:10.35659Z","end":"2026-02-11T01:44:10.555355Z","steps":["trace[1390517808] 'process raft request' (duration: 198.72874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:10.556925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.354011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-11T01:44:10.557396Z","caller":"traceutil/trace.go:171","msg":"trace[1890976484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"224.862693ms","start":"2026-02-11T01:44:10.332527Z","end":"2026-02-11T01:44:10.55739Z","steps":["trace[1890976484] 'agreement among raft nodes before linearized reading' (duration: 224.379591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:10.557267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.318516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-11T01:44:10.557562Z","caller":"traceutil/trace.go:171","msg":"trace[462757112] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:504; }","duration":"250.63332ms","start":"2026-02-11T01:44:10.306923Z","end":"2026-02-11T01:44:10.557556Z","steps":["trace[462757112] 'agreement among raft nodes before linearized reading' (duration: 250.315856ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:11.104458Z","caller":"traceutil/trace.go:171","msg":"trace[654444106] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"530.737052ms","start":"2026-02-11T01:44:10.573696Z","end":"2026-02-11T01:44:11.104433Z","steps":["trace[654444106] 'process raft request' (duration: 515.553898ms)","trace[654444106] 'compare' (duration: 14.315205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:11.104593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.573687Z","time spent":"530.844367ms","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4620,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:11.105216Z","caller":"traceutil/trace.go:171","msg":"trace[929027003] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"528.226237ms","start":"2026-02-11T01:44:10.576978Z","end":"2026-02-11T01:44:11.105204Z","steps":["trace[929027003] 'process raft request' (duration: 527.052464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:11.105287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.576972Z","time spent":"528.28088ms","remote":"127.0.0.1:40348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4595,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:11.105523Z","caller":"traceutil/trace.go:171","msg":"trace[1282103614] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"516.22735ms","start":"2026-02-11T01:44:10.589283Z","end":"2026-02-11T01:44:11.105511Z","steps":["trace[1282103614] 'process raft request' (duration: 514.804015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:11.105584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.589267Z","time spent":"516.284862ms","remote":"127.0.0.1:40368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:11.106177Z","caller":"traceutil/trace.go:171","msg":"trace[1413662509] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:521; }","duration":"460.937103ms","start":"2026-02-11T01:44:10.645227Z","end":"2026-02-11T01:44:11.106164Z","steps":["trace[1413662509] 'read index received' (duration: 444.02942ms)","trace[1413662509] 'applied index is now lower than readState.Index' (duration: 16.906693ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:11.106248Z","caller":"traceutil/trace.go:171","msg":"trace[1717416861] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"516.841717ms","start":"2026-02-11T01:44:10.589397Z","end":"2026-02-11T01:44:11.106239Z","steps":["trace[1717416861] 'process raft request' (duration: 514.730382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:11.106301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.589392Z","time spent":"516.87546ms","remote":"127.0.0.1:40364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14294,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:11.106528Z","caller":"traceutil/trace.go:171","msg":"trace[404425960] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"514.913779ms","start":"2026-02-11T01:44:10.591607Z","end":"2026-02-11T01:44:11.106521Z","steps":["trace[404425960] 'process raft request' (duration: 512.571323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:11.106563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.5916Z","time spent":"514.94593ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:44:11.106667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.438846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-11T01:44:11.106687Z","caller":"traceutil/trace.go:171","msg":"trace[654667435] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:509; }","duration":"461.480528ms","start":"2026-02-11T01:44:10.645201Z","end":"2026-02-11T01:44:11.106681Z","steps":["trace[654667435] 'agreement among raft nodes before linearized reading' (duration: 461.442916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:11.106704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.645191Z","time spent":"461.509429ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":562,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-11T01:44:11.106916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.206807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329928"} {"level":"info","ts":"2026-02-11T01:44:11.106942Z","caller":"traceutil/trace.go:171","msg":"trace[1779536867] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:509; }","duration":"451.252519ms","start":"2026-02-11T01:44:10.655683Z","end":"2026-02-11T01:44:11.106935Z","steps":["trace[1779536867] 'agreement among raft nodes before linearized reading' (duration: 451.096692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:11.106963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.655673Z","time spent":"451.283621ms","remote":"127.0.0.1:39950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329952,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-11T01:44:11.10722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.527428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:11.10724Z","caller":"traceutil/trace.go:171","msg":"trace[990713247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"322.571131ms","start":"2026-02-11T01:44:10.784663Z","end":"2026-02-11T01:44:11.107234Z","steps":["trace[990713247] 'agreement among raft nodes before linearized reading' (duration: 322.538809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:11.107257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:10.78465Z","time spent":"322.603002ms","remote":"127.0.0.1:39856","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-02-11T01:44:13.628771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529095199684452,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:44:13.81059Z","caller":"traceutil/trace.go:171","msg":"trace[1838993163] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"682.25623ms","start":"2026-02-11T01:44:13.12832Z","end":"2026-02-11T01:44:13.810576Z","steps":["trace[1838993163] 'read index received' (duration: 682.121764ms)","trace[1838993163] 'applied index is now lower than readState.Index' (duration: 133.776µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:13.810664Z","caller":"traceutil/trace.go:171","msg":"trace[1796153451] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"983.782087ms","start":"2026-02-11T01:44:12.826876Z","end":"2026-02-11T01:44:13.810658Z","steps":["trace[1796153451] 'process raft request' (duration: 983.604629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:13.810728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:12.826864Z","time spent":"983.816179ms","remote":"127.0.0.1:40038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:44:13.810937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.526302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T01:44:13.811008Z","caller":"traceutil/trace.go:171","msg":"trace[536303595] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"630.632856ms","start":"2026-02-11T01:44:13.180356Z","end":"2026-02-11T01:44:13.810989Z","steps":["trace[536303595] 'agreement among raft nodes before linearized reading' (duration: 630.455598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:13.811048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:13.18034Z","time spent":"630.700159ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:44:13.811168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.848046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-11T01:44:13.811194Z","caller":"traceutil/trace.go:171","msg":"trace[827998944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"682.909749ms","start":"2026-02-11T01:44:13.128277Z","end":"2026-02-11T01:44:13.811187Z","steps":["trace[827998944] 'agreement among raft nodes before linearized reading' (duration: 682.854776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:13.811211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:13.128258Z","time spent":"682.948831ms","remote":"127.0.0.1:40138","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-02-11T01:44:13.811294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.730882ms","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-02-11T01:44:13.811313Z","caller":"traceutil/trace.go:171","msg":"trace[404451679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"299.788765ms","start":"2026-02-11T01:44:13.511519Z","end":"2026-02-11T01:44:13.811308Z","steps":["trace[404451679] 'agreement among raft nodes before linearized reading' (duration: 299.733703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:17.029259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.906984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:44:17.029347Z","caller":"traceutil/trace.go:171","msg":"trace[1283077044] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"311.129214ms","start":"2026-02-11T01:44:16.7182Z","end":"2026-02-11T01:44:17.029329Z","steps":["trace[1283077044] 'process raft request' (duration: 24.095154ms)","trace[1283077044] 'compare' (duration: 286.5576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:17.029394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:16.718191Z","time spent":"311.185006ms","remote":"127.0.0.1:40234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:44:22.391389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.69322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:44:22.3915Z","caller":"traceutil/trace.go:171","msg":"trace[1987156346] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"327.320849ms","start":"2026-02-11T01:44:22.06416Z","end":"2026-02-11T01:44:22.391481Z","steps":["trace[1987156346] 'process raft request' (duration: 60.456452ms)","trace[1987156346] 'compare' (duration: 266.547434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:22.391566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.064149Z","time spent":"327.386241ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:44:22.894189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529095199684541,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:44:23.342777Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218143241s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:44:23.367273Z","caller":"traceutil/trace.go:171","msg":"trace[465291942] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.283757647s","start":"2026-02-11T01:44:22.083499Z","end":"2026-02-11T01:44:23.367257Z","steps":["trace[465291942] 'process raft request' (duration: 1.283734356s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:23.367357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.083493Z","time spent":"1.283820359s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:23.367519Z","caller":"traceutil/trace.go:171","msg":"trace[507350938] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.295042973s","start":"2026-02-11T01:44:22.072469Z","end":"2026-02-11T01:44:23.367512Z","steps":["trace[507350938] 'process raft request' (duration: 1.270471542s)","trace[507350938] 'compare' (duration: 23.81452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:23.367546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.072452Z","time spent":"1.295079365s","remote":"127.0.0.1:40138","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-02-11T01:44:23.367603Z","caller":"traceutil/trace.go:171","msg":"trace[1676631229] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.286516135s","start":"2026-02-11T01:44:22.081083Z","end":"2026-02-11T01:44:23.367599Z","steps":["trace[1676631229] 'process raft request' (duration: 1.286070268s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:23.367625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.081069Z","time spent":"1.286543997s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:23.367675Z","caller":"traceutil/trace.go:171","msg":"trace[1316993964] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.286530096s","start":"2026-02-11T01:44:22.08114Z","end":"2026-02-11T01:44:23.36767Z","steps":["trace[1316993964] 'process raft request' (duration: 1.286048847s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:23.367696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.081136Z","time spent":"1.286548596s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:23.367752Z","caller":"traceutil/trace.go:171","msg":"trace[1033378133] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.286557097s","start":"2026-02-11T01:44:22.081191Z","end":"2026-02-11T01:44:23.367748Z","steps":["trace[1033378133] 'process raft request' (duration: 1.286013946s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:23.367774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.081188Z","time spent":"1.286575678s","remote":"127.0.0.1:40050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:44:23.405337Z","caller":"traceutil/trace.go:171","msg":"trace[1621114192] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:581; }","duration":"1.0115421s","start":"2026-02-11T01:44:22.393779Z","end":"2026-02-11T01:44:23.405321Z","steps":["trace[1621114192] 'read index received' (duration: 949.13618ms)","trace[1621114192] 'applied index is now lower than readState.Index' (duration: 62.40525ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:23.405413Z","caller":"traceutil/trace.go:171","msg":"trace[422827537] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"942.876233ms","start":"2026-02-11T01:44:22.462532Z","end":"2026-02-11T01:44:23.405408Z","steps":["trace[422827537] 'process raft request' (duration: 942.694956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:23.405509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.462513Z","time spent":"942.914115ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:44:23.405596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.503289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:23.405648Z","caller":"traceutil/trace.go:171","msg":"trace[598816446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"577.587983ms","start":"2026-02-11T01:44:22.828046Z","end":"2026-02-11T01:44:23.405633Z","steps":["trace[598816446] 'agreement among raft nodes before linearized reading' (duration: 577.504889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:23.405675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.828033Z","time spent":"577.636235ms","remote":"127.0.0.1:39856","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-02-11T01:44:23.405741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011961036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-11T01:44:23.405762Z","caller":"traceutil/trace.go:171","msg":"trace[1739741709] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:567; }","duration":"1.012015488s","start":"2026-02-11T01:44:22.393741Z","end":"2026-02-11T01:44:23.405756Z","steps":["trace[1739741709] 'agreement among raft nodes before linearized reading' (duration: 1.011973007s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:23.405778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:22.393724Z","time spent":"1.012051609s","remote":"127.0.0.1:40034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-11T01:44:25.874652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529095199684555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:44:26.375584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529095199684555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:44:26.876224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529095199684555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:44:27.376705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529095199684555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:44:27.842068Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.848806835s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:44:27.842456Z","caller":"traceutil/trace.go:171","msg":"trace[63084664] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"2.849263482s","start":"2026-02-11T01:44:24.993173Z","end":"2026-02-11T01:44:27.842436Z","steps":["trace[63084664] 'process raft request' (duration: 2.849150978s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:27.842553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:24.993147Z","time spent":"2.849345335s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:44:28.328264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.370836ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T01:44:28.328379Z","caller":"traceutil/trace.go:171","msg":"trace[1875436055] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"2.954168073s","start":"2026-02-11T01:44:25.374191Z","end":"2026-02-11T01:44:28.328359Z","steps":["trace[1875436055] 'read index received' (duration: 2.468645698s)","trace[1875436055] 'applied index is now lower than readState.Index' (duration: 485.518315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:28.328594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.954388752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-11T01:44:28.328623Z","caller":"traceutil/trace.go:171","msg":"trace[691092087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"2.954450754s","start":"2026-02-11T01:44:25.374164Z","end":"2026-02-11T01:44:28.328614Z","steps":["trace[691092087] 'agreement among raft nodes before linearized reading' (duration: 2.954314869s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:28.328652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:25.374151Z","time spent":"2.954494666s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:44:28.328937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.954695764s","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-02-11T01:44:28.328958Z","caller":"traceutil/trace.go:171","msg":"trace[494420732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"2.954725175s","start":"2026-02-11T01:44:25.374227Z","end":"2026-02-11T01:44:28.328952Z","steps":["trace[494420732] 'agreement among raft nodes before linearized reading' (duration: 2.954661892s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:28.328977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:25.374222Z","time spent":"2.954750375s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:44:28.329091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.555506514s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:28.32911Z","caller":"traceutil/trace.go:171","msg":"trace[1900596108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"1.555545826s","start":"2026-02-11T01:44:26.773557Z","end":"2026-02-11T01:44:28.329103Z","steps":["trace[1900596108] 'agreement among raft nodes before linearized reading' (duration: 1.555518865s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:28.329127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:26.773544Z","time spent":"1.555579598s","remote":"127.0.0.1:39856","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-02-11T01:44:28.329242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.953661274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:44:28.32926Z","caller":"traceutil/trace.go:171","msg":"trace[1902391004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"2.953692675s","start":"2026-02-11T01:44:25.375561Z","end":"2026-02-11T01:44:28.329254Z","steps":["trace[1902391004] 'agreement among raft nodes before linearized reading' (duration: 2.953641643s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:28.329278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:25.375555Z","time spent":"2.953718555s","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:44:28.329433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.954548647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T01:44:28.329455Z","caller":"traceutil/trace.go:171","msg":"trace[935729227] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"2.954583679s","start":"2026-02-11T01:44:25.374864Z","end":"2026-02-11T01:44:28.329448Z","steps":["trace[935729227] 'agreement among raft nodes before linearized reading' (duration: 2.954519176s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:28.329473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:25.374858Z","time spent":"2.954611169s","remote":"127.0.0.1:40138","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-02-11T01:44:28.450653Z","caller":"traceutil/trace.go:171","msg":"trace[864827847] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"103.131746ms","start":"2026-02-11T01:44:28.347504Z","end":"2026-02-11T01:44:28.450636Z","steps":["trace[864827847] 'process raft request' (duration: 102.994391ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:28.451004Z","caller":"traceutil/trace.go:171","msg":"trace[1180288294] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"103.399206ms","start":"2026-02-11T01:44:28.347581Z","end":"2026-02-11T01:44:28.45098Z","steps":["trace[1180288294] 'process raft request' (duration: 103.014242ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:29.423266Z","caller":"traceutil/trace.go:171","msg":"trace[466660776] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"106.987454ms","start":"2026-02-11T01:44:29.316261Z","end":"2026-02-11T01:44:29.423249Z","steps":["trace[466660776] 'process raft request' (duration: 106.88674ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:30.131756Z","caller":"traceutil/trace.go:171","msg":"trace[742496519] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"115.769015ms","start":"2026-02-11T01:44:30.015936Z","end":"2026-02-11T01:44:30.131705Z","steps":["trace[742496519] 'process raft request' (duration: 115.559237ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:30.146576Z","caller":"traceutil/trace.go:171","msg":"trace[1955831808] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"113.739392ms","start":"2026-02-11T01:44:30.032819Z","end":"2026-02-11T01:44:30.146559Z","steps":["trace[1955831808] 'process raft request' (duration: 113.554425ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:31.180055Z","caller":"traceutil/trace.go:171","msg":"trace[795209632] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"130.67719ms","start":"2026-02-11T01:44:31.049355Z","end":"2026-02-11T01:44:31.180033Z","steps":["trace[795209632] 'process raft request' (duration: 130.540795ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:34.125743Z","caller":"traceutil/trace.go:171","msg":"trace[810689161] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"795.887677ms","start":"2026-02-11T01:44:33.329801Z","end":"2026-02-11T01:44:34.125689Z","steps":["trace[810689161] 'process raft request' (duration: 776.101194ms)","trace[810689161] 'compare' (duration: 19.021947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:34.125859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:33.329782Z","time spent":"796.025882ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:44:34.239878Z","caller":"traceutil/trace.go:171","msg":"trace[1425674862] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:653; }","duration":"463.385838ms","start":"2026-02-11T01:44:33.776475Z","end":"2026-02-11T01:44:34.239861Z","steps":["trace[1425674862] 'read index received' (duration: 329.438889ms)","trace[1425674862] 'applied index is now lower than readState.Index' (duration: 133.946399ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:34.240641Z","caller":"traceutil/trace.go:171","msg":"trace[391546837] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"447.825855ms","start":"2026-02-11T01:44:33.792795Z","end":"2026-02-11T01:44:34.240621Z","steps":["trace[391546837] 'process raft request' (duration: 446.951105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.240744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:33.792778Z","time spent":"447.915758ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:44:34.240901Z","caller":"traceutil/trace.go:171","msg":"trace[1971235069] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"310.805597ms","start":"2026-02-11T01:44:33.930079Z","end":"2026-02-11T01:44:34.240885Z","steps":["trace[1971235069] 'process raft request' (duration: 309.757641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.240942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:33.930056Z","time spent":"310.865209ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T01:44:34.241021Z","caller":"traceutil/trace.go:171","msg":"trace[769074915] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"713.618663ms","start":"2026-02-11T01:44:33.527371Z","end":"2026-02-11T01:44:34.24099Z","steps":["trace[769074915] 'process raft request' (duration: 704.354594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.241071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:33.527353Z","time spent":"713.690156ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:44:34.241943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.46105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:34.241972Z","caller":"traceutil/trace.go:171","msg":"trace[607489907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"465.517051ms","start":"2026-02-11T01:44:33.776447Z","end":"2026-02-11T01:44:34.241964Z","steps":["trace[607489907] 'agreement among raft nodes before linearized reading' (duration: 464.673572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.241992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:33.776433Z","time spent":"465.553892ms","remote":"127.0.0.1:39870","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-02-11T01:44:34.242876Z","caller":"traceutil/trace.go:171","msg":"trace[1983189912] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"681.254034ms","start":"2026-02-11T01:44:33.561601Z","end":"2026-02-11T01:44:34.242855Z","steps":["trace[1983189912] 'process raft request' (duration: 678.023773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.242951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:44:33.561578Z","time spent":"681.327787ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:44:34.249728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.398296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:44:34.249784Z","caller":"traceutil/trace.go:171","msg":"trace[1146623353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:636; }","duration":"258.501679ms","start":"2026-02-11T01:44:33.991264Z","end":"2026-02-11T01:44:34.249766Z","steps":["trace[1146623353] 'agreement among raft nodes before linearized reading' (duration: 258.326863ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:34.250803Z","caller":"traceutil/trace.go:171","msg":"trace[1051896426] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"105.806929ms","start":"2026-02-11T01:44:34.144984Z","end":"2026-02-11T01:44:34.250791Z","steps":["trace[1051896426] 'process raft request' (duration: 104.365429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.252295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.082248ms","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-02-11T01:44:34.252319Z","caller":"traceutil/trace.go:171","msg":"trace[2023828268] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:636; }","duration":"117.14563ms","start":"2026-02-11T01:44:34.135167Z","end":"2026-02-11T01:44:34.252313Z","steps":["trace[2023828268] 'agreement among raft nodes before linearized reading' (duration: 117.103159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.252888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.513048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tmbjl\" ","response":"range_response_count:1 size:17914"} {"level":"info","ts":"2026-02-11T01:44:34.252919Z","caller":"traceutil/trace.go:171","msg":"trace[380095337] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tmbjl; range_end:; response_count:1; response_revision:636; }","duration":"183.56999ms","start":"2026-02-11T01:44:34.069334Z","end":"2026-02-11T01:44:34.252904Z","steps":["trace[380095337] 'agreement among raft nodes before linearized reading' (duration: 183.495938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:34.614735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.397924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T01:44:34.614779Z","caller":"traceutil/trace.go:171","msg":"trace[711162767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"105.474987ms","start":"2026-02-11T01:44:34.509294Z","end":"2026-02-11T01:44:34.614769Z","steps":["trace[711162767] 'range keys from in-memory index tree' (duration: 105.320072ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:35.704056Z","caller":"traceutil/trace.go:171","msg":"trace[1936265138] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"148.715701ms","start":"2026-02-11T01:44:35.555313Z","end":"2026-02-11T01:44:35.704029Z","steps":["trace[1936265138] 'process raft request' (duration: 148.38611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:35.921332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.796776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:35.921725Z","caller":"traceutil/trace.go:171","msg":"trace[278486978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"142.194419ms","start":"2026-02-11T01:44:35.779518Z","end":"2026-02-11T01:44:35.921712Z","steps":["trace[278486978] 'range keys from in-memory index tree' (duration: 141.750264ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:35.92262Z","caller":"traceutil/trace.go:171","msg":"trace[1113102493] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"113.974036ms","start":"2026-02-11T01:44:35.808639Z","end":"2026-02-11T01:44:35.922613Z","steps":["trace[1113102493] 'process raft request' (duration: 110.379924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:36.636459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.465295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:44:36.636533Z","caller":"traceutil/trace.go:171","msg":"trace[581650772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"295.573829ms","start":"2026-02-11T01:44:36.340943Z","end":"2026-02-11T01:44:36.636517Z","steps":["trace[581650772] 'range keys from in-memory index tree' (duration: 295.338751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:36.637277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.374465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-11T01:44:36.637389Z","caller":"traceutil/trace.go:171","msg":"trace[1821589291] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:657; }","duration":"296.455869ms","start":"2026-02-11T01:44:36.340857Z","end":"2026-02-11T01:44:36.637313Z","steps":["trace[1821589291] 'range keys from in-memory index tree' (duration: 296.119677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:36.637911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.687829ms","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-02-11T01:44:36.63796Z","caller":"traceutil/trace.go:171","msg":"trace[628017986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:657; }","duration":"192.767332ms","start":"2026-02-11T01:44:36.445182Z","end":"2026-02-11T01:44:36.637949Z","steps":["trace[628017986] 'range keys from in-memory index tree' (duration: 192.580885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:36.638431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.864317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:36.638545Z","caller":"traceutil/trace.go:171","msg":"trace[1492921220] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:657; }","duration":"155.924549ms","start":"2026-02-11T01:44:36.482542Z","end":"2026-02-11T01:44:36.638466Z","steps":["trace[1492921220] 'range keys from in-memory index tree' (duration: 155.836426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:36.638974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.450987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:36.639099Z","caller":"traceutil/trace.go:171","msg":"trace[1859441753] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:657; }","duration":"156.510849ms","start":"2026-02-11T01:44:36.482505Z","end":"2026-02-11T01:44:36.639016Z","steps":["trace[1859441753] 'count revisions from in-memory index tree' (duration: 156.365794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:36.639654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.024286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:44:36.639783Z","caller":"traceutil/trace.go:171","msg":"trace[1967602130] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:657; }","duration":"157.083668ms","start":"2026-02-11T01:44:36.482607Z","end":"2026-02-11T01:44:36.639691Z","steps":["trace[1967602130] 'range keys from in-memory index tree' (duration: 157.001976ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:36.641088Z","caller":"traceutil/trace.go:171","msg":"trace[340757007] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"137.060993ms","start":"2026-02-11T01:44:36.504009Z","end":"2026-02-11T01:44:36.64107Z","steps":["trace[340757007] 'process raft request' (duration: 120.529665ms)","trace[340757007] 'compare' (duration: 11.587671ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:36.698172Z","caller":"traceutil/trace.go:171","msg":"trace[1958817777] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"193.832067ms","start":"2026-02-11T01:44:36.504326Z","end":"2026-02-11T01:44:36.698158Z","steps":["trace[1958817777] 'process raft request' (duration: 193.354731ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:36.697831Z","caller":"traceutil/trace.go:171","msg":"trace[1740107318] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"188.238868ms","start":"2026-02-11T01:44:36.50957Z","end":"2026-02-11T01:44:36.697809Z","steps":["trace[1740107318] 'read index received' (duration: 114.991188ms)","trace[1740107318] 'applied index is now lower than readState.Index' (duration: 73.24665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:36.702964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.372761ms","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-02-11T01:44:36.703238Z","caller":"traceutil/trace.go:171","msg":"trace[65906141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"193.511516ms","start":"2026-02-11T01:44:36.509546Z","end":"2026-02-11T01:44:36.703058Z","steps":["trace[65906141] 'agreement among raft nodes before linearized reading' (duration: 193.154855ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:36.809919Z","caller":"traceutil/trace.go:171","msg":"trace[687848644] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"154.592423ms","start":"2026-02-11T01:44:36.6553Z","end":"2026-02-11T01:44:36.809893Z","steps":["trace[687848644] 'process raft request' (duration: 117.150981ms)","trace[687848644] 'compare' (duration: 36.795971ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:36.81092Z","caller":"traceutil/trace.go:171","msg":"trace[2000399907] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"155.542855ms","start":"2026-02-11T01:44:36.655363Z","end":"2026-02-11T01:44:36.810906Z","steps":["trace[2000399907] 'process raft request' (duration: 154.007924ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:36.811361Z","caller":"traceutil/trace.go:171","msg":"trace[800511120] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:682; }","duration":"108.191309ms","start":"2026-02-11T01:44:36.703156Z","end":"2026-02-11T01:44:36.811347Z","steps":["trace[800511120] 'read index received' (duration: 69.305177ms)","trace[800511120] 'applied index is now lower than readState.Index' (duration: 38.884842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:44:36.8121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.579215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T01:44:36.812928Z","caller":"traceutil/trace.go:171","msg":"trace[1534727727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"184.670008ms","start":"2026-02-11T01:44:36.627467Z","end":"2026-02-11T01:44:36.812137Z","steps":["trace[1534727727] 'agreement among raft nodes before linearized reading' (duration: 184.532374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:36.813452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.853875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-11T01:44:36.813793Z","caller":"traceutil/trace.go:171","msg":"trace[1331164882] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:663; }","duration":"286.984919ms","start":"2026-02-11T01:44:36.526515Z","end":"2026-02-11T01:44:36.813499Z","steps":["trace[1331164882] 'agreement among raft nodes before linearized reading' (duration: 286.792032ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:43.075542Z","caller":"traceutil/trace.go:171","msg":"trace[1669181344] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"172.567409ms","start":"2026-02-11T01:44:42.902947Z","end":"2026-02-11T01:44:43.075514Z","steps":["trace[1669181344] 'read index received' (duration: 171.467084ms)","trace[1669181344] 'applied index is now lower than readState.Index' (duration: 1.099035ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:44:43.076985Z","caller":"traceutil/trace.go:171","msg":"trace[293302007] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"177.452442ms","start":"2026-02-11T01:44:42.899513Z","end":"2026-02-11T01:44:43.076966Z","steps":["trace[293302007] 'process raft request' (duration: 174.890182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:43.077324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.372314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T01:44:43.077361Z","caller":"traceutil/trace.go:171","msg":"trace[975530586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"174.436077ms","start":"2026-02-11T01:44:42.902913Z","end":"2026-02-11T01:44:43.077349Z","steps":["trace[975530586] 'agreement among raft nodes before linearized reading' (duration: 174.310653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:44:43.078436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.445169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:44:43.078478Z","caller":"traceutil/trace.go:171","msg":"trace[797864175] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"175.49733ms","start":"2026-02-11T01:44:42.902969Z","end":"2026-02-11T01:44:43.078466Z","steps":["trace[797864175] 'agreement among raft nodes before linearized reading' (duration: 175.374857ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:44.284852Z","caller":"traceutil/trace.go:171","msg":"trace[1190102636] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"120.823867ms","start":"2026-02-11T01:44:44.163646Z","end":"2026-02-11T01:44:44.28447Z","steps":["trace[1190102636] 'process raft request' (duration: 120.677242ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:44:44.436701Z","caller":"traceutil/trace.go:171","msg":"trace[299975779] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"145.429808ms","start":"2026-02-11T01:44:44.291251Z","end":"2026-02-11T01:44:44.436681Z","steps":["trace[299975779] 'process raft request' (duration: 137.036369ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:37.898742Z","caller":"traceutil/trace.go:171","msg":"trace[1224985272] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"121.23482ms","start":"2026-02-11T01:45:37.777481Z","end":"2026-02-11T01:45:37.898716Z","steps":["trace[1224985272] 'read index received' (duration: 121.047196ms)","trace[1224985272] 'applied index is now lower than readState.Index' (duration: 186.814µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:45:37.898891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.391613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:37.898915Z","caller":"traceutil/trace.go:171","msg":"trace[1434382305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"121.468476ms","start":"2026-02-11T01:45:37.777438Z","end":"2026-02-11T01:45:37.898907Z","steps":["trace[1434382305] 'agreement among raft nodes before linearized reading' (duration: 121.369654ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:37.899171Z","caller":"traceutil/trace.go:171","msg":"trace[1953249110] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"146.312449ms","start":"2026-02-11T01:45:37.752851Z","end":"2026-02-11T01:45:37.899163Z","steps":["trace[1953249110] 'process raft request' (duration: 145.744179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:39.716227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.040977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-11T01:45:39.716283Z","caller":"traceutil/trace.go:171","msg":"trace[1342597424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"131.970449ms","start":"2026-02-11T01:45:39.584299Z","end":"2026-02-11T01:45:39.71627Z","steps":["trace[1342597424] 'range keys from in-memory index tree' (duration: 116.944294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:39.923329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.931659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:39.923402Z","caller":"traceutil/trace.go:171","msg":"trace[1779260506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"148.037152ms","start":"2026-02-11T01:45:39.775344Z","end":"2026-02-11T01:45:39.923382Z","steps":["trace[1779260506] 'agreement among raft nodes before linearized reading' (duration: 26.210734ms)","trace[1779260506] 'range keys from in-memory index tree' (duration: 121.737966ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:40.045124Z","caller":"traceutil/trace.go:171","msg":"trace[466410850] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"115.155155ms","start":"2026-02-11T01:45:39.929946Z","end":"2026-02-11T01:45:40.045101Z","steps":["trace[466410850] 'process raft request' (duration: 114.993782ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:44.226127Z","caller":"traceutil/trace.go:171","msg":"trace[1522031524] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"102.741409ms","start":"2026-02-11T01:45:44.123357Z","end":"2026-02-11T01:45:44.226098Z","steps":["trace[1522031524] 'process raft request' (duration: 102.624016ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:45.587291Z","caller":"traceutil/trace.go:171","msg":"trace[323380353] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"128.851258ms","start":"2026-02-11T01:45:45.458413Z","end":"2026-02-11T01:45:45.587265Z","steps":["trace[323380353] 'process raft request' (duration: 128.684025ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:45.591169Z","caller":"traceutil/trace.go:171","msg":"trace[1651027278] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"130.719612ms","start":"2026-02-11T01:45:45.460427Z","end":"2026-02-11T01:45:45.591147Z","steps":["trace[1651027278] 'process raft request' (duration: 130.564389ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:49.273762Z","caller":"traceutil/trace.go:171","msg":"trace[96543607] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"164.144054ms","start":"2026-02-11T01:45:49.109599Z","end":"2026-02-11T01:45:49.273743Z","steps":["trace[96543607] 'read index received' (duration: 164.027922ms)","trace[96543607] 'applied index is now lower than readState.Index' (duration: 115.292µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:49.273854Z","caller":"traceutil/trace.go:171","msg":"trace[1840449743] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"179.883621ms","start":"2026-02-11T01:45:49.093963Z","end":"2026-02-11T01:45:49.273847Z","steps":["trace[1840449743] 'process raft request' (duration: 179.672377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.274231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.606732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:45:49.274263Z","caller":"traceutil/trace.go:171","msg":"trace[2014088327] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:967; }","duration":"164.676314ms","start":"2026-02-11T01:45:49.109577Z","end":"2026-02-11T01:45:49.274254Z","steps":["trace[2014088327] 'agreement among raft nodes before linearized reading' (duration: 164.5336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:45:49.27445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.992856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:45:49.274475Z","caller":"traceutil/trace.go:171","msg":"trace[1891626696] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:967; }","duration":"113.042717ms","start":"2026-02-11T01:45:49.161426Z","end":"2026-02-11T01:45:49.274469Z","steps":["trace[1891626696] 'agreement among raft nodes before linearized reading' (duration: 113.002217ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:49.570314Z","caller":"traceutil/trace.go:171","msg":"trace[768066390] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"146.585477ms","start":"2026-02-11T01:45:49.423677Z","end":"2026-02-11T01:45:49.570262Z","steps":["trace[768066390] 'process raft request' (duration: 146.463315ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:45:49.998016Z","caller":"traceutil/trace.go:171","msg":"trace[1384101143] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"120.934055ms","start":"2026-02-11T01:45:49.877049Z","end":"2026-02-11T01:45:49.997983Z","steps":["trace[1384101143] 'process raft request' (duration: 84.22102ms)","trace[1384101143] 'compare' (duration: 36.174746ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:45:50.917121Z","caller":"traceutil/trace.go:171","msg":"trace[1579833901] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"100.467093ms","start":"2026-02-11T01:45:50.816631Z","end":"2026-02-11T01:45:50.917098Z","steps":["trace[1579833901] 'process raft request' (duration: 87.615079ms)","trace[1579833901] 'compare' (duration: 12.314565ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:00.036801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.146334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-11T01:46:00.037098Z","caller":"traceutil/trace.go:171","msg":"trace[317238025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"229.4536ms","start":"2026-02-11T01:45:59.807621Z","end":"2026-02-11T01:46:00.037074Z","steps":["trace[317238025] 'range keys from in-memory index tree' (duration: 228.95549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:00.037795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.585357ms","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-02-11T01:46:00.037861Z","caller":"traceutil/trace.go:171","msg":"trace[1770782950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"125.690638ms","start":"2026-02-11T01:45:59.912162Z","end":"2026-02-11T01:46:00.037852Z","steps":["trace[1770782950] 'range keys from in-memory index tree' (duration: 125.503246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:00.766411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.078137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T01:46:00.766504Z","caller":"traceutil/trace.go:171","msg":"trace[326126923] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1059; }","duration":"704.347702ms","start":"2026-02-11T01:46:00.062141Z","end":"2026-02-11T01:46:00.766488Z","steps":["trace[326126923] 'read index received' (duration: 53.271973ms)","trace[326126923] 'applied index is now lower than readState.Index' (duration: 651.074389ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:00.767437Z","caller":"traceutil/trace.go:171","msg":"trace[1391235879] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"715.912911ms","start":"2026-02-11T01:46:00.051504Z","end":"2026-02-11T01:46:00.767417Z","steps":["trace[1391235879] 'process raft request' (duration: 93.741306ms)","trace[1391235879] 'compare' (duration: 620.812413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:00.767701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:00.05149Z","time spent":"715.988062ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:46:00.768115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.971268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T01:46:00.768159Z","caller":"traceutil/trace.go:171","msg":"trace[272296711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"706.04842ms","start":"2026-02-11T01:46:00.0621Z","end":"2026-02-11T01:46:00.768148Z","steps":["trace[272296711] 'agreement among raft nodes before linearized reading' (duration: 705.908037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:00.768552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:00.062086Z","time spent":"706.385386ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:46:00.768433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.711467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-11T01:46:00.768812Z","caller":"traceutil/trace.go:171","msg":"trace[50121643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"304.104984ms","start":"2026-02-11T01:46:00.464666Z","end":"2026-02-11T01:46:00.768771Z","steps":["trace[50121643] 'agreement among raft nodes before linearized reading' (duration: 303.566125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:00.76897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:00.464652Z","time spent":"304.232205ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-11T01:46:01.267172Z","caller":"traceutil/trace.go:171","msg":"trace[1973031383] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"490.4491ms","start":"2026-02-11T01:46:00.776705Z","end":"2026-02-11T01:46:01.267154Z","steps":["trace[1973031383] 'process raft request' (duration: 490.345928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:01.26741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:46:00.776682Z","time spent":"490.644572ms","remote":"127.0.0.1:40138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:46:02.581188Z","caller":"traceutil/trace.go:171","msg":"trace[479837718] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1027; }","duration":"102.167935ms","start":"2026-02-11T01:46:02.479Z","end":"2026-02-11T01:46:02.581168Z","steps":["trace[479837718] 'process raft request' (duration: 93.040916ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:13.106222Z","caller":"traceutil/trace.go:171","msg":"trace[656644873] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"130.887085ms","start":"2026-02-11T01:46:12.975313Z","end":"2026-02-11T01:46:13.1062Z","steps":["trace[656644873] 'read index received' (duration: 113.101914ms)","trace[656644873] 'applied index is now lower than readState.Index' (duration: 17.784121ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:46:13.106901Z","caller":"traceutil/trace.go:171","msg":"trace[1019869167] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"134.207756ms","start":"2026-02-11T01:46:12.972674Z","end":"2026-02-11T01:46:13.106882Z","steps":["trace[1019869167] 'process raft request' (duration: 115.735774ms)","trace[1019869167] 'compare' (duration: 17.700361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:46:13.107215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.88048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-controller-manager\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-02-11T01:46:13.107444Z","caller":"traceutil/trace.go:171","msg":"trace[484596659] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"132.123243ms","start":"2026-02-11T01:46:12.975299Z","end":"2026-02-11T01:46:13.107422Z","steps":["trace[484596659] 'agreement among raft nodes before linearized reading' (duration: 131.858889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:46:16.351631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.661083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-02-11T01:46:16.35188Z","caller":"traceutil/trace.go:171","msg":"trace[202455468] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:1065; }","duration":"111.953678ms","start":"2026-02-11T01:46:16.239908Z","end":"2026-02-11T01:46:16.351861Z","steps":["trace[202455468] 'range keys from in-memory index tree' (duration: 111.564592ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:46:25.384392Z","caller":"traceutil/trace.go:171","msg":"trace[1027076350] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"125.359978ms","start":"2026-02-11T01:46:25.259006Z","end":"2026-02-11T01:46:25.384366Z","steps":["trace[1027076350] 'process raft request' (duration: 90.778741ms)","trace[1027076350] 'compare' (duration: 34.243402ms)"],"step_count":2}