{"level":"warn","ts":"2026-02-25T02:07:31.134312Z","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-25T02:07:31.134404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.102:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.102:2380","--initial-cluster=instance=https://199.204.45.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.102: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-25T02:07:31.134464Z","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-25T02:07:31.134476Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-02-25T02:07:31.134494Z","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-25T02:07:31.135293Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"]} {"level":"info","ts":"2026-02-25T02:07:31.13538Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.102: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-25T02:07:31.140673Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.114112ms"} {"level":"info","ts":"2026-02-25T02:07:31.150004Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e7960f761c1028e","cluster-id":"502e2b31b867adf"} {"level":"info","ts":"2026-02-25T02:07:31.150111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:07:31.150151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 0"} {"level":"info","ts":"2026-02-25T02:07:31.150175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e7960f761c1028e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:07:31.150197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 1"} {"level":"info","ts":"2026-02-25T02:07:31.150247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"warn","ts":"2026-02-25T02:07:31.157483Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:07:31.158913Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:07:31.160016Z","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-25T02:07:31.164354Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e7960f761c1028e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:07:31.166345Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e7960f761c1028e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:07:31.166477Z","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-25T02:07:31.166521Z","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-25T02:07:31.16654Z","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-25T02:07:31.167967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"} {"level":"info","ts":"2026-02-25T02:07:31.168138Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","added-peer-id":"6e7960f761c1028e","added-peer-peer-urls":["https://199.204.45.102:2380"]} {"level":"info","ts":"2026-02-25T02:07:31.170965Z","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-25T02:07:31.171071Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-02-25T02:07:31.171114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.102:2380"} {"level":"info","ts":"2026-02-25T02:07:31.171181Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e7960f761c1028e","initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:07:31.171219Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:07:32.051121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:07:32.051195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:07:32.051245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgPreVoteResp from 6e7960f761c1028e at term 1"} {"level":"info","ts":"2026-02-25T02:07:32.051338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:07:32.051352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgVoteResp from 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-02-25T02:07:32.051371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became leader at term 2"} {"level":"info","ts":"2026-02-25T02:07:32.051387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e7960f761c1028e elected leader 6e7960f761c1028e at term 2"} {"level":"info","ts":"2026-02-25T02:07:32.052435Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e7960f761c1028e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.102:2379]}","request-path":"/0/members/6e7960f761c1028e/attributes","cluster-id":"502e2b31b867adf","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:07:32.052487Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:32.052717Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:07:32.053082Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:07:32.053172Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:07:32.053237Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:07:32.05643Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:07:32.057033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:32.057149Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:32.057179Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:07:32.061642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.102:2379"} {"level":"warn","ts":"2026-02-25T02:07:35.546654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.723122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:35.546843Z","caller":"traceutil/trace.go:171","msg":"trace[1382247156] linearizableReadLoop","detail":"{readStateIndex:177; appliedIndex:176; }","duration":"607.640477ms","start":"2026-02-25T02:07:34.939144Z","end":"2026-02-25T02:07:35.546784Z","steps":["trace[1382247156] 'read index received' (duration: 27.099822ms)","trace[1382247156] 'applied index is now lower than readState.Index' (duration: 580.539085ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:35.547023Z","caller":"traceutil/trace.go:171","msg":"trace[833948781] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"702.072195ms","start":"2026-02-25T02:07:34.844922Z","end":"2026-02-25T02:07:35.546994Z","steps":["trace[833948781] 'process raft request' (duration: 121.346589ms)","trace[833948781] 'compare' (duration: 579.566571ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:35.54712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:34.844909Z","time spent":"702.173116ms","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:35.547366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.151229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:35.547513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.348399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:35.548218Z","caller":"traceutil/trace.go:171","msg":"trace[992590194] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:173; }","duration":"609.016143ms","start":"2026-02-25T02:07:34.939136Z","end":"2026-02-25T02:07:35.548152Z","steps":["trace[992590194] 'agreement among raft nodes before linearized reading' (duration: 608.002719ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:35.548406Z","caller":"traceutil/trace.go:171","msg":"trace[1264356199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:173; }","duration":"174.214822ms","start":"2026-02-25T02:07:35.374112Z","end":"2026-02-25T02:07:35.548326Z","steps":["trace[1264356199] 'agreement among raft nodes before linearized reading' (duration: 173.356989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:35.548444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:34.939076Z","time spent":"609.294694ms","remote":"127.0.0.1:42272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-02-25T02:07:35.547812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.143858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:35.548562Z","caller":"traceutil/trace.go:171","msg":"trace[728068338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:173; }","duration":"217.915321ms","start":"2026-02-25T02:07:35.330616Z","end":"2026-02-25T02:07:35.548532Z","steps":["trace[728068338] 'agreement among raft nodes before linearized reading' (duration: 216.421725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:36.647983Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.095857668s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T02:07:38.31199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663585328s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:07:38.312107Z","caller":"traceutil/trace.go:171","msg":"trace[1876267054] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"2.760085348s","start":"2026-02-25T02:07:35.551999Z","end":"2026-02-25T02:07:38.312085Z","steps":["trace[1876267054] 'process raft request' (duration: 1.096308639s)","trace[1876267054] 'compare' (duration: 1.663081516s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:38.312163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.551985Z","time spent":"2.760153518s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:38.354741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999930204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:07:38.354849Z","caller":"traceutil/trace.go:171","msg":"trace[1934414758] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000114875s","start":"2026-02-25T02:07:36.354714Z","end":"2026-02-25T02:07:38.354829Z","steps":["trace[1934414758] 'agreement among raft nodes before linearized reading' (duration: 1.999963094s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.354908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.354686Z","time spent":"2.000206435s","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:07:38.630564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000158863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T02:07:38.630733Z","caller":"traceutil/trace.go:171","msg":"trace[27088941] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000391454s","start":"2026-02-25T02:07:36.63032Z","end":"2026-02-25T02:07:38.630711Z","steps":["trace[27088941] 'agreement among raft nodes before linearized reading' (duration: 2.000192713s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.630813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.630298Z","time spent":"2.000501914s","remote":"127.0.0.1:42114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:07:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:07:38.774511Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.126078319s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:07:38.775657Z","caller":"traceutil/trace.go:171","msg":"trace[1984041179] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"3.223357512s","start":"2026-02-25T02:07:35.552268Z","end":"2026-02-25T02:07:38.775626Z","steps":["trace[1984041179] 'process raft request' (duration: 3.222557678s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:38.775723Z","caller":"traceutil/trace.go:171","msg":"trace[1048026988] linearizableReadLoop","detail":"{readStateIndex:183; appliedIndex:177; }","duration":"2.420920338s","start":"2026-02-25T02:07:36.354781Z","end":"2026-02-25T02:07:38.775701Z","steps":["trace[1048026988] 'read index received' (duration: 293.546564ms)","trace[1048026988] 'applied index is now lower than readState.Index' (duration: 2.127372964s)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:38.775827Z","caller":"traceutil/trace.go:171","msg":"trace[94461331] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"3.223261441s","start":"2026-02-25T02:07:35.552557Z","end":"2026-02-25T02:07:38.775818Z","steps":["trace[94461331] 'process raft request' (duration: 3.22299386s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.775842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.552251Z","time spent":"3.223486742s","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5860,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:07:38.775888Z","caller":"traceutil/trace.go:171","msg":"trace[1265955867] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"2.423229648s","start":"2026-02-25T02:07:36.35265Z","end":"2026-02-25T02:07:38.77588Z","steps":["trace[1265955867] 'process raft request' (duration: 2.423020767s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.775967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:36.352617Z","time spent":"2.423299588s","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:38.776054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.552545Z","time spent":"3.223309621s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:38.776126Z","caller":"traceutil/trace.go:171","msg":"trace[239797393] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"3.223440242s","start":"2026-02-25T02:07:35.552623Z","end":"2026-02-25T02:07:38.776063Z","steps":["trace[239797393] 'process raft request' (duration: 3.22298631s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:38.775814Z","caller":"traceutil/trace.go:171","msg":"trace[315823564] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"3.222254677s","start":"2026-02-25T02:07:35.553551Z","end":"2026-02-25T02:07:38.775805Z","steps":["trace[315823564] 'process raft request' (duration: 3.222084517s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.776313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.552616Z","time spent":"3.223595863s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:07:38.776335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:35.553536Z","time spent":"3.222778369s","remote":"127.0.0.1:42472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":732,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:07:38.780635Z","caller":"traceutil/trace.go:171","msg":"trace[1182020102] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"1.321587038s","start":"2026-02-25T02:07:37.459032Z","end":"2026-02-25T02:07:38.780619Z","steps":["trace[1182020102] 'process raft request' (duration: 1.321463587s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:38.780672Z","caller":"traceutil/trace.go:171","msg":"trace[345546827] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"666.416261ms","start":"2026-02-25T02:07:38.114245Z","end":"2026-02-25T02:07:38.780661Z","steps":["trace[345546827] 'process raft request' (duration: 666.30925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.780727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:38.114222Z","time spent":"666.477981ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:38.780728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:37.458989Z","time spent":"1.321698278s","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3943,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T02:07:38.780757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:38.318495Z","time spent":"462.26065ms","remote":"127.0.0.1:42210","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T02:07:38.780725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.179907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:07:38.780798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.98892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:07:38.780838Z","caller":"traceutil/trace.go:171","msg":"trace[1223307353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:181; }","duration":"464.327319ms","start":"2026-02-25T02:07:38.316499Z","end":"2026-02-25T02:07:38.780826Z","steps":["trace[1223307353] 'agreement among raft nodes before linearized reading' (duration: 464.182498ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:38.780872Z","caller":"traceutil/trace.go:171","msg":"trace[206694665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:181; }","duration":"924.032821ms","start":"2026-02-25T02:07:37.856798Z","end":"2026-02-25T02:07:38.78083Z","steps":["trace[206694665] 'agreement among raft nodes before linearized reading' (duration: 923.94255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.781365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.68232ms","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-25T02:07:38.781546Z","caller":"traceutil/trace.go:171","msg":"trace[1599025787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:181; }","duration":"464.89951ms","start":"2026-02-25T02:07:38.316627Z","end":"2026-02-25T02:07:38.781526Z","steps":["trace[1599025787] 'agreement among raft nodes before linearized reading' (duration: 464.122387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:38.781676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:38.316615Z","time spent":"465.048901ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:07:38.780914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:37.856774Z","time spent":"924.130091ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:07:38.780881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:38.316458Z","time spent":"464.410839ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:07:42.914403Z","caller":"traceutil/trace.go:171","msg":"trace[1025708377] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:349; }","duration":"117.715985ms","start":"2026-02-25T02:07:42.796662Z","end":"2026-02-25T02:07:42.914378Z","steps":["trace[1025708377] 'read index received' (duration: 80.794759ms)","trace[1025708377] 'applied index is now lower than readState.Index' (duration: 36.920156ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:07:42.914471Z","caller":"traceutil/trace.go:171","msg":"trace[1550316836] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"120.144894ms","start":"2026-02-25T02:07:42.794301Z","end":"2026-02-25T02:07:42.914446Z","steps":["trace[1550316836] 'process raft request' (duration: 107.340043ms)","trace[1550316836] 'compare' (duration: 12.63649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:07:42.914575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.897196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T02:07:42.914619Z","caller":"traceutil/trace.go:171","msg":"trace[333854914] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:339; }","duration":"117.968746ms","start":"2026-02-25T02:07:42.79664Z","end":"2026-02-25T02:07:42.914609Z","steps":["trace[333854914] 'agreement among raft nodes before linearized reading' (duration: 117.881075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:42.914614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.981542ms","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-25T02:07:42.914655Z","caller":"traceutil/trace.go:171","msg":"trace[860397230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:339; }","duration":"112.057312ms","start":"2026-02-25T02:07:42.802585Z","end":"2026-02-25T02:07:42.914643Z","steps":["trace[860397230] 'agreement among raft nodes before linearized reading' (duration: 111.950681ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:07:42.914764Z","caller":"traceutil/trace.go:171","msg":"trace[893857992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:339; }","duration":"100.037764ms","start":"2026-02-25T02:07:42.814693Z","end":"2026-02-25T02:07:42.914731Z","steps":["trace[893857992] 'agreement among raft nodes before linearized reading' (duration: 99.960824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:44.21901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.594401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T02:07:44.219121Z","caller":"traceutil/trace.go:171","msg":"trace[18688527] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:345; }","duration":"620.756512ms","start":"2026-02-25T02:07:43.598339Z","end":"2026-02-25T02:07:44.219096Z","steps":["trace[18688527] 'range keys from in-memory index tree' (duration: 620.489851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:44.219181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:43.598322Z","time spent":"620.843382ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T02:07:44.307958Z","caller":"traceutil/trace.go:171","msg":"trace[97610687] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"560.093833ms","start":"2026-02-25T02:07:43.747839Z","end":"2026-02-25T02:07:44.307933Z","steps":["trace[97610687] 'process raft request' (duration: 559.937092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:07:44.308149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:07:43.747812Z","time spent":"560.252173ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:07:44.379342Z","caller":"traceutil/trace.go:171","msg":"trace[842838877] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"156.963483ms","start":"2026-02-25T02:07:44.22235Z","end":"2026-02-25T02:07:44.379313Z","steps":["trace[842838877] 'process raft request' (duration: 115.22067ms)","trace[842838877] 'compare' (duration: 41.595462ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:07.31014Z","caller":"traceutil/trace.go:171","msg":"trace[87877472] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"230.659549ms","start":"2026-02-25T02:08:07.07946Z","end":"2026-02-25T02:08:07.31012Z","steps":["trace[87877472] 'read index received' (duration: 230.430909ms)","trace[87877472] 'applied index is now lower than readState.Index' (duration: 227.67µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:07.310208Z","caller":"traceutil/trace.go:171","msg":"trace[988747239] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"314.183176ms","start":"2026-02-25T02:08:06.995993Z","end":"2026-02-25T02:08:07.310177Z","steps":["trace[988747239] 'process raft request' (duration: 313.989828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:07.310281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.803566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T02:08:07.310342Z","caller":"traceutil/trace.go:171","msg":"trace[327038529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"230.91322ms","start":"2026-02-25T02:08:07.079414Z","end":"2026-02-25T02:08:07.310327Z","steps":["trace[327038529] 'agreement among raft nodes before linearized reading' (duration: 230.790705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:07.310641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.581156ms","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-25T02:08:07.31068Z","caller":"traceutil/trace.go:171","msg":"trace[1487799368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"114.737843ms","start":"2026-02-25T02:08:07.195928Z","end":"2026-02-25T02:08:07.310666Z","steps":["trace[1487799368] 'agreement among raft nodes before linearized reading' (duration: 114.380657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:07.310768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.061475ms","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-25T02:08:07.310787Z","caller":"traceutil/trace.go:171","msg":"trace[1493482901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"213.595898ms","start":"2026-02-25T02:08:07.097184Z","end":"2026-02-25T02:08:07.31078Z","steps":["trace[1493482901] 'agreement among raft nodes before linearized reading' (duration: 213.080495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:07.311147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:06.995976Z","time spent":"314.325313ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:09.827714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016353927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:08:09.881086Z","caller":"traceutil/trace.go:171","msg":"trace[180071161] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"554.313402ms","start":"2026-02-25T02:08:09.326752Z","end":"2026-02-25T02:08:09.881065Z","steps":["trace[180071161] 'read index received' (duration: 554.255949ms)","trace[180071161] 'applied index is now lower than readState.Index' (duration: 56.613µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:09.881201Z","caller":"traceutil/trace.go:171","msg":"trace[1456278033] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"585.645213ms","start":"2026-02-25T02:08:09.295516Z","end":"2026-02-25T02:08:09.881161Z","steps":["trace[1456278033] 'process raft request' (duration: 585.413173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:09.881323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.555422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T02:08:09.881337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.295494Z","time spent":"585.776339ms","remote":"127.0.0.1:42210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:09.881361Z","caller":"traceutil/trace.go:171","msg":"trace[1336255418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"554.636925ms","start":"2026-02-25T02:08:09.326716Z","end":"2026-02-25T02:08:09.881353Z","steps":["trace[1336255418] 'agreement among raft nodes before linearized reading' (duration: 554.468318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:09.881388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.326701Z","time spent":"554.681707ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:09.881703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.024957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:08:09.881685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.828476ms","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-25T02:08:09.881721Z","caller":"traceutil/trace.go:171","msg":"trace[1875647249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"441.069179ms","start":"2026-02-25T02:08:09.440646Z","end":"2026-02-25T02:08:09.881716Z","steps":["trace[1875647249] 'agreement among raft nodes before linearized reading' (duration: 441.036057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:09.881709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.44602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T02:08:09.881701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.782591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:08:09.881745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.440632Z","time spent":"441.109241ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:08:09.88176Z","caller":"traceutil/trace.go:171","msg":"trace[1196461536] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"474.954932ms","start":"2026-02-25T02:08:09.406783Z","end":"2026-02-25T02:08:09.881738Z","steps":["trace[1196461536] 'agreement among raft nodes before linearized reading' (duration: 474.696631ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:09.881769Z","caller":"traceutil/trace.go:171","msg":"trace[1100358758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"497.577545ms","start":"2026-02-25T02:08:09.384173Z","end":"2026-02-25T02:08:09.88175Z","steps":["trace[1100358758] 'agreement among raft nodes before linearized reading' (duration: 497.403718ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:09.881778Z","caller":"traceutil/trace.go:171","msg":"trace[1210722543] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:529; }","duration":"372.889946ms","start":"2026-02-25T02:08:09.50887Z","end":"2026-02-25T02:08:09.88176Z","steps":["trace[1210722543] 'agreement among raft nodes before linearized reading' (duration: 372.76149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:09.881805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.406763Z","time spent":"475.033215ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:08:09.881812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.508851Z","time spent":"372.952799ms","remote":"127.0.0.1:42224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-25T02:08:09.88182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.384153Z","time spent":"497.650599ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:09.882178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.385371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T02:08:09.881561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.88603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T02:08:09.88222Z","caller":"traceutil/trace.go:171","msg":"trace[803641053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"475.475334ms","start":"2026-02-25T02:08:09.406735Z","end":"2026-02-25T02:08:09.882211Z","steps":["trace[803641053] 'agreement among raft nodes before linearized reading' (duration: 475.127699ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:09.882235Z","caller":"traceutil/trace.go:171","msg":"trace[1513899764] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:529; }","duration":"553.59131ms","start":"2026-02-25T02:08:09.328634Z","end":"2026-02-25T02:08:09.882225Z","steps":["trace[1513899764] 'agreement among raft nodes before linearized reading' (duration: 552.781955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:09.88225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.406712Z","time spent":"475.529497ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T02:08:09.882277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:09.328629Z","time spent":"553.639172ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T02:08:10.287922Z","caller":"traceutil/trace.go:171","msg":"trace[1881977467] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"153.02834ms","start":"2026-02-25T02:08:10.134875Z","end":"2026-02-25T02:08:10.287903Z","steps":["trace[1881977467] 'process raft request' (duration: 152.923505ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:10.310324Z","caller":"traceutil/trace.go:171","msg":"trace[1655201033] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"174.151196ms","start":"2026-02-25T02:08:10.136155Z","end":"2026-02-25T02:08:10.310306Z","steps":["trace[1655201033] 'process raft request' (duration: 173.966268ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:10.310352Z","caller":"traceutil/trace.go:171","msg":"trace[259384980] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"137.45443ms","start":"2026-02-25T02:08:10.172873Z","end":"2026-02-25T02:08:10.310327Z","steps":["trace[259384980] 'process raft request' (duration: 137.412549ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:10.310586Z","caller":"traceutil/trace.go:171","msg":"trace[800934835] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"172.070557ms","start":"2026-02-25T02:08:10.138496Z","end":"2026-02-25T02:08:10.310567Z","steps":["trace[800934835] 'process raft request' (duration: 171.733992ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:10.866532Z","caller":"traceutil/trace.go:171","msg":"trace[5093531] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"105.07502ms","start":"2026-02-25T02:08:10.761439Z","end":"2026-02-25T02:08:10.866514Z","steps":["trace[5093531] 'process raft request' (duration: 104.965495ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:11.028851Z","caller":"traceutil/trace.go:171","msg":"trace[88071792] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"257.539814ms","start":"2026-02-25T02:08:10.771287Z","end":"2026-02-25T02:08:11.028827Z","steps":["trace[88071792] 'read index received' (duration: 95.148965ms)","trace[88071792] 'applied index is now lower than readState.Index' (duration: 162.389719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:11.029163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.744122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:11.02921Z","caller":"traceutil/trace.go:171","msg":"trace[1349720059] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:560; }","duration":"257.94483ms","start":"2026-02-25T02:08:10.771249Z","end":"2026-02-25T02:08:11.029193Z","steps":["trace[1349720059] 'agreement among raft nodes before linearized reading' (duration: 257.680619ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:11.029245Z","caller":"traceutil/trace.go:171","msg":"trace[775930211] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"261.688721ms","start":"2026-02-25T02:08:10.767529Z","end":"2026-02-25T02:08:11.029218Z","steps":["trace[775930211] 'process raft request' (duration: 261.168529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:11.260642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.555639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:11.26091Z","caller":"traceutil/trace.go:171","msg":"trace[255341547] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"179.906808ms","start":"2026-02-25T02:08:11.080977Z","end":"2026-02-25T02:08:11.260884Z","steps":["trace[255341547] 'process raft request' (duration: 56.028986ms)","trace[255341547] 'compare' (duration: 123.390981ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:11.2799Z","caller":"traceutil/trace.go:171","msg":"trace[26232983] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"108.558683ms","start":"2026-02-25T02:08:11.171317Z","end":"2026-02-25T02:08:11.279875Z","steps":["trace[26232983] 'read index received' (duration: 108.535822ms)","trace[26232983] 'applied index is now lower than readState.Index' (duration: 21.931µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:11.279948Z","caller":"traceutil/trace.go:171","msg":"trace[438505125] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"166.112684ms","start":"2026-02-25T02:08:11.1138Z","end":"2026-02-25T02:08:11.279912Z","steps":["trace[438505125] 'process raft request' (duration: 165.948767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:11.280033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.689189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:11.280073Z","caller":"traceutil/trace.go:171","msg":"trace[562506193] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:565; }","duration":"108.785293ms","start":"2026-02-25T02:08:11.171274Z","end":"2026-02-25T02:08:11.280059Z","steps":["trace[562506193] 'agreement among raft nodes before linearized reading' (duration: 108.70401ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:11.537128Z","caller":"traceutil/trace.go:171","msg":"trace[2075669147] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"125.608756ms","start":"2026-02-25T02:08:11.411468Z","end":"2026-02-25T02:08:11.537077Z","steps":["trace[2075669147] 'process raft request' (duration: 111.503448ms)","trace[2075669147] 'compare' (duration: 13.948922ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:11.57902Z","caller":"traceutil/trace.go:171","msg":"trace[1073730298] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"163.369067ms","start":"2026-02-25T02:08:11.415628Z","end":"2026-02-25T02:08:11.578997Z","steps":["trace[1073730298] 'process raft request' (duration: 163.011212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:11.579249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.262893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:11.579356Z","caller":"traceutil/trace.go:171","msg":"trace[2124671987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"138.42303ms","start":"2026-02-25T02:08:11.440905Z","end":"2026-02-25T02:08:11.579328Z","steps":["trace[2124671987] 'agreement among raft nodes before linearized reading' (duration: 138.238312ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:11.579481Z","caller":"traceutil/trace.go:171","msg":"trace[1856914839] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"137.892787ms","start":"2026-02-25T02:08:11.440948Z","end":"2026-02-25T02:08:11.578841Z","steps":["trace[1856914839] 'read index received' (duration: 82.06241ms)","trace[1856914839] 'applied index is now lower than readState.Index' (duration: 55.828927ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:11.684877Z","caller":"traceutil/trace.go:171","msg":"trace[1537585005] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"141.224248ms","start":"2026-02-25T02:08:11.543628Z","end":"2026-02-25T02:08:11.684852Z","steps":["trace[1537585005] 'process raft request' (duration: 139.673673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:11.68502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.791525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:11.685069Z","caller":"traceutil/trace.go:171","msg":"trace[155640673] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:574; }","duration":"142.897829ms","start":"2026-02-25T02:08:11.542158Z","end":"2026-02-25T02:08:11.685056Z","steps":["trace[155640673] 'agreement among raft nodes before linearized reading' (duration: 142.807435ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:11.684918Z","caller":"traceutil/trace.go:171","msg":"trace[952130718] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:590; }","duration":"105.360827ms","start":"2026-02-25T02:08:11.579536Z","end":"2026-02-25T02:08:11.684897Z","steps":["trace[952130718] 'read index received' (duration: 103.759899ms)","trace[952130718] 'applied index is now lower than readState.Index' (duration: 1.600048ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:11.685203Z","caller":"traceutil/trace.go:171","msg":"trace[1703248370] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"110.445642ms","start":"2026-02-25T02:08:11.574743Z","end":"2026-02-25T02:08:11.685189Z","steps":["trace[1703248370] 'process raft request' (duration: 110.108198ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:11.685246Z","caller":"traceutil/trace.go:171","msg":"trace[1769078384] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"141.159705ms","start":"2026-02-25T02:08:11.544073Z","end":"2026-02-25T02:08:11.685233Z","steps":["trace[1769078384] 'process raft request' (duration: 140.692076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:11.685303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.874797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:11.685361Z","caller":"traceutil/trace.go:171","msg":"trace[1365703835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"104.980181ms","start":"2026-02-25T02:08:11.580365Z","end":"2026-02-25T02:08:11.685345Z","steps":["trace[1365703835] 'agreement among raft nodes before linearized reading' (duration: 104.871936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.106757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.623684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:12.106841Z","caller":"traceutil/trace.go:171","msg":"trace[1392037545] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"419.039595ms","start":"2026-02-25T02:08:11.687786Z","end":"2026-02-25T02:08:12.106826Z","steps":["trace[1392037545] 'process raft request' (duration: 106.291787ms)","trace[1392037545] 'compare' (duration: 312.471957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:12.106883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:11.68776Z","time spent":"419.107738ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2550,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:12.107613Z","caller":"traceutil/trace.go:171","msg":"trace[1690630027] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"416.306579ms","start":"2026-02-25T02:08:11.691286Z","end":"2026-02-25T02:08:12.107592Z","steps":["trace[1690630027] 'read index received' (duration: 102.772298ms)","trace[1690630027] 'applied index is now lower than readState.Index' (duration: 313.533461ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:12.107676Z","caller":"traceutil/trace.go:171","msg":"trace[1215450279] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"407.768657ms","start":"2026-02-25T02:08:11.699875Z","end":"2026-02-25T02:08:12.107644Z","steps":["trace[1215450279] 'process raft request' (duration: 407.657033ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.107787Z","caller":"traceutil/trace.go:171","msg":"trace[1082143954] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"416.918065ms","start":"2026-02-25T02:08:11.690821Z","end":"2026-02-25T02:08:12.107739Z","steps":["trace[1082143954] 'process raft request' (duration: 416.600452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.107808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:11.699841Z","time spent":"407.896922ms","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:12.107869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:11.690789Z","time spent":"417.03164ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:12.1082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.886993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3343"} {"level":"info","ts":"2026-02-25T02:08:12.108252Z","caller":"traceutil/trace.go:171","msg":"trace[1344015734] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:577; }","duration":"416.969947ms","start":"2026-02-25T02:08:11.691269Z","end":"2026-02-25T02:08:12.108239Z","steps":["trace[1344015734] 'agreement among raft nodes before linearized reading' (duration: 416.440725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.108368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:11.691245Z","time spent":"417.109953ms","remote":"127.0.0.1:42568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":3365,"request content":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-02-25T02:08:12.134753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.887803ms","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-25T02:08:12.134804Z","caller":"traceutil/trace.go:171","msg":"trace[1925131686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"230.962636ms","start":"2026-02-25T02:08:11.903832Z","end":"2026-02-25T02:08:12.134794Z","steps":["trace[1925131686] 'agreement among raft nodes before linearized reading' (duration: 230.828661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.134841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.023518ms","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-25T02:08:12.134884Z","caller":"traceutil/trace.go:171","msg":"trace[434283832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"231.061791ms","start":"2026-02-25T02:08:11.90381Z","end":"2026-02-25T02:08:12.134872Z","steps":["trace[434283832] 'agreement among raft nodes before linearized reading' (duration: 230.974967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.13505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.892716ms","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-25T02:08:12.135058Z","caller":"traceutil/trace.go:171","msg":"trace[1592208274] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"300.957754ms","start":"2026-02-25T02:08:11.834043Z","end":"2026-02-25T02:08:12.135001Z","steps":["trace[1592208274] 'process raft request' (duration: 300.425332ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.135077Z","caller":"traceutil/trace.go:171","msg":"trace[2080313336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"231.966579ms","start":"2026-02-25T02:08:11.903104Z","end":"2026-02-25T02:08:12.13507Z","steps":["trace[2080313336] 'agreement among raft nodes before linearized reading' (duration: 231.903826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.135179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:11.834018Z","time spent":"301.057288ms","remote":"127.0.0.1:42298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4367,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:12.135339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.207378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T02:08:12.135352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.793741ms","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-25T02:08:12.135359Z","caller":"traceutil/trace.go:171","msg":"trace[312590664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"232.265131ms","start":"2026-02-25T02:08:11.903089Z","end":"2026-02-25T02:08:12.135354Z","steps":["trace[312590664] 'agreement among raft nodes before linearized reading' (duration: 232.216309ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.135373Z","caller":"traceutil/trace.go:171","msg":"trace[1967044240] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:578; }","duration":"131.837782ms","start":"2026-02-25T02:08:12.003528Z","end":"2026-02-25T02:08:12.135366Z","steps":["trace[1967044240] 'agreement among raft nodes before linearized reading' (duration: 131.78279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.134757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.565665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-25T02:08:12.135503Z","caller":"traceutil/trace.go:171","msg":"trace[511057798] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:578; }","duration":"396.307447ms","start":"2026-02-25T02:08:11.739136Z","end":"2026-02-25T02:08:12.135443Z","steps":["trace[511057798] 'agreement among raft nodes before linearized reading' (duration: 395.472292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.135563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.295959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-25T02:08:12.135585Z","caller":"traceutil/trace.go:171","msg":"trace[1076548421] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"164.370642ms","start":"2026-02-25T02:08:11.971208Z","end":"2026-02-25T02:08:12.135579Z","steps":["trace[1076548421] 'agreement among raft nodes before linearized reading' (duration: 164.246997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.135551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.719205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-02-25T02:08:12.135626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:11.739114Z","time spent":"396.484414ms","remote":"127.0.0.1:42224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12749,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-02-25T02:08:12.135688Z","caller":"traceutil/trace.go:171","msg":"trace[2015295608] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:578; }","duration":"364.059919ms","start":"2026-02-25T02:08:11.77161Z","end":"2026-02-25T02:08:12.135669Z","steps":["trace[2015295608] 'agreement among raft nodes before linearized reading' (duration: 363.549788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.135734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:11.771578Z","time spent":"364.144052ms","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-25T02:08:12.266007Z","caller":"traceutil/trace.go:171","msg":"trace[2089055102] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"152.629562ms","start":"2026-02-25T02:08:12.11336Z","end":"2026-02-25T02:08:12.26599Z","steps":["trace[2089055102] 'process raft request' (duration: 152.456725ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.266098Z","caller":"traceutil/trace.go:171","msg":"trace[1638068772] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"154.752101ms","start":"2026-02-25T02:08:12.111309Z","end":"2026-02-25T02:08:12.266061Z","steps":["trace[1638068772] 'process raft request' (duration: 141.990187ms)","trace[1638068772] 'compare' (duration: 12.379088ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:12.266165Z","caller":"traceutil/trace.go:171","msg":"trace[845953381] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"152.019117ms","start":"2026-02-25T02:08:12.114136Z","end":"2026-02-25T02:08:12.266155Z","steps":["trace[845953381] 'process raft request' (duration: 151.770817ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.266055Z","caller":"traceutil/trace.go:171","msg":"trace[1082729246] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"152.08655ms","start":"2026-02-25T02:08:12.113937Z","end":"2026-02-25T02:08:12.266024Z","steps":["trace[1082729246] 'process raft request' (duration: 151.934544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.266247Z","caller":"traceutil/trace.go:171","msg":"trace[1923339566] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"153.918356ms","start":"2026-02-25T02:08:12.112311Z","end":"2026-02-25T02:08:12.266229Z","steps":["trace[1923339566] 'process raft request' (duration: 153.475438ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.266394Z","caller":"traceutil/trace.go:171","msg":"trace[789529836] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"147.408054ms","start":"2026-02-25T02:08:12.118975Z","end":"2026-02-25T02:08:12.266383Z","steps":["trace[789529836] 'process raft request' (duration: 146.959455ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.343449Z","caller":"traceutil/trace.go:171","msg":"trace[399228556] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"204.317157ms","start":"2026-02-25T02:08:12.13911Z","end":"2026-02-25T02:08:12.343428Z","steps":["trace[399228556] 'process raft request' (duration: 203.90502ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.343777Z","caller":"traceutil/trace.go:171","msg":"trace[188115669] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"203.903139ms","start":"2026-02-25T02:08:12.13986Z","end":"2026-02-25T02:08:12.343763Z","steps":["trace[188115669] 'process raft request' (duration: 203.294874ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.344103Z","caller":"traceutil/trace.go:171","msg":"trace[485545964] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:597; }","duration":"172.986405ms","start":"2026-02-25T02:08:12.171104Z","end":"2026-02-25T02:08:12.344091Z","steps":["trace[485545964] 'read index received' (duration: 82.213513ms)","trace[485545964] 'applied index is now lower than readState.Index' (duration: 90.771722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:12.344256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.140951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-25T02:08:12.344288Z","caller":"traceutil/trace.go:171","msg":"trace[1926449867] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:589; }","duration":"173.187973ms","start":"2026-02-25T02:08:12.171092Z","end":"2026-02-25T02:08:12.34428Z","steps":["trace[1926449867] 'agreement among raft nodes before linearized reading' (duration: 173.042707ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.344424Z","caller":"traceutil/trace.go:171","msg":"trace[316709187] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"202.381746ms","start":"2026-02-25T02:08:12.142034Z","end":"2026-02-25T02:08:12.344416Z","steps":["trace[316709187] 'process raft request' (duration: 202.017511ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.34447Z","caller":"traceutil/trace.go:171","msg":"trace[1589403069] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"202.987481ms","start":"2026-02-25T02:08:12.141431Z","end":"2026-02-25T02:08:12.344418Z","steps":["trace[1589403069] 'process raft request' (duration: 202.48931ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.344532Z","caller":"traceutil/trace.go:171","msg":"trace[830011474] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"204.067456ms","start":"2026-02-25T02:08:12.14043Z","end":"2026-02-25T02:08:12.344497Z","steps":["trace[830011474] 'process raft request' (duration: 202.97083ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.591748Z","caller":"traceutil/trace.go:171","msg":"trace[1093253370] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"319.373916ms","start":"2026-02-25T02:08:12.27235Z","end":"2026-02-25T02:08:12.591724Z","steps":["trace[1093253370] 'process raft request' (duration: 319.202519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.591925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:12.272325Z","time spent":"319.498891ms","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3210,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:12.611394Z","caller":"traceutil/trace.go:171","msg":"trace[892974352] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"170.180488ms","start":"2026-02-25T02:08:12.441189Z","end":"2026-02-25T02:08:12.611369Z","steps":["trace[892974352] 'read index received' (duration: 150.548656ms)","trace[892974352] 'applied index is now lower than readState.Index' (duration: 19.630812ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:12.611442Z","caller":"traceutil/trace.go:171","msg":"trace[993811634] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"237.761598ms","start":"2026-02-25T02:08:12.373657Z","end":"2026-02-25T02:08:12.611419Z","steps":["trace[993811634] 'process raft request' (duration: 237.604971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.611587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.368755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:12.611699Z","caller":"traceutil/trace.go:171","msg":"trace[719516519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"170.521702ms","start":"2026-02-25T02:08:12.441154Z","end":"2026-02-25T02:08:12.611676Z","steps":["trace[719516519] 'agreement among raft nodes before linearized reading' (duration: 170.302633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.81033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.925299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:12.810405Z","caller":"traceutil/trace.go:171","msg":"trace[1586578587] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"198.938701ms","start":"2026-02-25T02:08:12.611455Z","end":"2026-02-25T02:08:12.810394Z","steps":["trace[1586578587] 'read index received' (duration: 64.970531ms)","trace[1586578587] 'applied index is now lower than readState.Index' (duration: 133.96729ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:12.810448Z","caller":"traceutil/trace.go:171","msg":"trace[356003118] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"210.745136ms","start":"2026-02-25T02:08:12.599698Z","end":"2026-02-25T02:08:12.810443Z","steps":["trace[356003118] 'process raft request' (duration: 76.64669ms)","trace[356003118] 'compare' (duration: 133.842856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:12.81058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.327102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-25T02:08:12.810599Z","caller":"traceutil/trace.go:171","msg":"trace[1520225252] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:592; }","duration":"238.381564ms","start":"2026-02-25T02:08:12.572212Z","end":"2026-02-25T02:08:12.810593Z","steps":["trace[1520225252] 'agreement among raft nodes before linearized reading' (duration: 238.307721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:12.857845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.804673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:12.857898Z","caller":"traceutil/trace.go:171","msg":"trace[412895877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"244.899467ms","start":"2026-02-25T02:08:12.612988Z","end":"2026-02-25T02:08:12.857887Z","steps":["trace[412895877] 'agreement among raft nodes before linearized reading' (duration: 244.804423ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:12.858033Z","caller":"traceutil/trace.go:171","msg":"trace[976003641] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"238.183925ms","start":"2026-02-25T02:08:12.61984Z","end":"2026-02-25T02:08:12.858023Z","steps":["trace[976003641] 'process raft request' (duration: 237.864572ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:13.555057Z","caller":"traceutil/trace.go:171","msg":"trace[1007643352] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"689.564238ms","start":"2026-02-25T02:08:12.865466Z","end":"2026-02-25T02:08:13.55503Z","steps":["trace[1007643352] 'process raft request' (duration: 629.002072ms)","trace[1007643352] 'compare' (duration: 60.294425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:13.555217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:12.865445Z","time spent":"689.684643ms","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:13.560778Z","caller":"traceutil/trace.go:171","msg":"trace[969224823] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:612; }","duration":"119.007693ms","start":"2026-02-25T02:08:13.441737Z","end":"2026-02-25T02:08:13.560745Z","steps":["trace[969224823] 'read index received' (duration: 52.814935ms)","trace[969224823] 'applied index is now lower than readState.Index' (duration: 66.191238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:13.560984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.206961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:13.561029Z","caller":"traceutil/trace.go:171","msg":"trace[1835109852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"119.333897ms","start":"2026-02-25T02:08:13.441684Z","end":"2026-02-25T02:08:13.561018Z","steps":["trace[1835109852] 'agreement among raft nodes before linearized reading' (duration: 119.189431ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:13.561079Z","caller":"traceutil/trace.go:171","msg":"trace[1807785] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"360.119867ms","start":"2026-02-25T02:08:13.200917Z","end":"2026-02-25T02:08:13.561037Z","steps":["trace[1807785] 'process raft request' (duration: 359.410008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:13.561276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:13.200885Z","time spent":"360.320375ms","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:13.561346Z","caller":"traceutil/trace.go:171","msg":"trace[415860746] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"153.361385ms","start":"2026-02-25T02:08:13.407969Z","end":"2026-02-25T02:08:13.561331Z","steps":["trace[415860746] 'process raft request' (duration: 152.566592ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:13.561423Z","caller":"traceutil/trace.go:171","msg":"trace[1522616204] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"583.796745ms","start":"2026-02-25T02:08:12.977617Z","end":"2026-02-25T02:08:13.561414Z","steps":["trace[1522616204] 'process raft request' (duration: 582.500441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:13.561451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:12.977595Z","time spent":"583.842847ms","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:14.438028Z","caller":"traceutil/trace.go:171","msg":"trace[2073558244] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"420.635739ms","start":"2026-02-25T02:08:14.017368Z","end":"2026-02-25T02:08:14.438003Z","steps":["trace[2073558244] 'process raft request' (duration: 420.39176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:14.438189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:14.017338Z","time spent":"420.766766ms","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:14.438817Z","caller":"traceutil/trace.go:171","msg":"trace[171390606] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:622; }","duration":"266.71242ms","start":"2026-02-25T02:08:14.172083Z","end":"2026-02-25T02:08:14.438795Z","steps":["trace[171390606] 'read index received' (duration: 266.704329ms)","trace[171390606] 'applied index is now lower than readState.Index' (duration: 6.131µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:14.439072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.921369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-25T02:08:14.439096Z","caller":"traceutil/trace.go:171","msg":"trace[882486353] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:603; }","duration":"267.070835ms","start":"2026-02-25T02:08:14.172015Z","end":"2026-02-25T02:08:14.439086Z","steps":["trace[882486353] 'agreement among raft nodes before linearized reading' (duration: 266.876307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:14.452089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.161165ms","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-25T02:08:14.452145Z","caller":"traceutil/trace.go:171","msg":"trace[478606781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"102.26932ms","start":"2026-02-25T02:08:14.349864Z","end":"2026-02-25T02:08:14.452134Z","steps":["trace[478606781] 'agreement among raft nodes before linearized reading' (duration: 102.147335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:14.452203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.351442ms","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-25T02:08:14.452257Z","caller":"traceutil/trace.go:171","msg":"trace[538493227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"101.453606ms","start":"2026-02-25T02:08:14.350791Z","end":"2026-02-25T02:08:14.452245Z","steps":["trace[538493227] 'agreement among raft nodes before linearized reading' (duration: 101.283409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:14.452324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.818651ms","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-25T02:08:14.452448Z","caller":"traceutil/trace.go:171","msg":"trace[1991745065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"101.963387ms","start":"2026-02-25T02:08:14.350474Z","end":"2026-02-25T02:08:14.452437Z","steps":["trace[1991745065] 'agreement among raft nodes before linearized reading' (duration: 101.805251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:14.452596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.851522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-25T02:08:14.452639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.107203ms","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-25T02:08:14.452645Z","caller":"traceutil/trace.go:171","msg":"trace[997480965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:603; }","duration":"100.946305ms","start":"2026-02-25T02:08:14.351686Z","end":"2026-02-25T02:08:14.452632Z","steps":["trace[997480965] 'agreement among raft nodes before linearized reading' (duration: 100.708075ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:14.452669Z","caller":"traceutil/trace.go:171","msg":"trace[1613834630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:603; }","duration":"102.726308ms","start":"2026-02-25T02:08:14.349932Z","end":"2026-02-25T02:08:14.452659Z","steps":["trace[1613834630] 'agreement among raft nodes before linearized reading' (duration: 102.02943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:15.718562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016354088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:16.218966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016354088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:16.369662Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.248495275s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:08:16.369995Z","caller":"traceutil/trace.go:171","msg":"trace[315342672] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"1.152144705s","start":"2026-02-25T02:08:15.217828Z","end":"2026-02-25T02:08:16.369973Z","steps":["trace[315342672] 'read index received' (duration: 1.151926706s)","trace[315342672] 'applied index is now lower than readState.Index' (duration: 217.099µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:16.370115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152280221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:16.37015Z","caller":"traceutil/trace.go:171","msg":"trace[1300611382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"1.152368924s","start":"2026-02-25T02:08:15.217771Z","end":"2026-02-25T02:08:16.37014Z","steps":["trace[1300611382] 'agreement among raft nodes before linearized reading' (duration: 1.152301161s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:16.370128Z","caller":"traceutil/trace.go:171","msg":"trace[1662130235] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.249023146s","start":"2026-02-25T02:08:15.121061Z","end":"2026-02-25T02:08:16.370084Z","steps":["trace[1662130235] 'process raft request' (duration: 1.248768846s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:16.370183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:15.217746Z","time spent":"1.152428567s","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:08:16.370195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.164484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:16.370241Z","caller":"traceutil/trace.go:171","msg":"trace[645249703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"929.266137ms","start":"2026-02-25T02:08:15.440964Z","end":"2026-02-25T02:08:16.37023Z","steps":["trace[645249703] 'agreement among raft nodes before linearized reading' (duration: 929.169753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:16.370264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:15.440943Z","time spent":"929.316469ms","remote":"127.0.0.1:42114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:08:16.370297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:15.121047Z","time spent":"1.249164311s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:21.446878Z","caller":"traceutil/trace.go:171","msg":"trace[1252807373] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"415.826457ms","start":"2026-02-25T02:08:21.031021Z","end":"2026-02-25T02:08:21.446847Z","steps":["trace[1252807373] 'process raft request' (duration: 415.666811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:21.447031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:21.030996Z","time spent":"415.962462ms","remote":"127.0.0.1:42312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:22.940844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016354140,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:23.442006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016354140,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:23.942806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016354140,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:24.440437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999917733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T02:08:24.440548Z","caller":"traceutil/trace.go:171","msg":"trace[960888092] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000082449s","start":"2026-02-25T02:08:22.440442Z","end":"2026-02-25T02:08:24.440525Z","steps":["trace[960888092] 'agreement among raft nodes before linearized reading' (duration: 1.999949035s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.440601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:22.440423Z","time spent":"2.000166472s","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 02:08:24 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T02:08:24.443925Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016354140,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T02:08:24.916292Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.707544091s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T02:08:24.916835Z","caller":"traceutil/trace.go:171","msg":"trace[277144322] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"2.708169744s","start":"2026-02-25T02:08:22.208643Z","end":"2026-02-25T02:08:24.916812Z","steps":["trace[277144322] 'process raft request' (duration: 2.707866883s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:24.917035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:22.208618Z","time spent":"2.708318389s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:25.062444Z","caller":"traceutil/trace.go:171","msg":"trace[1858576594] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"2.621919322s","start":"2026-02-25T02:08:22.440491Z","end":"2026-02-25T02:08:25.06241Z","steps":["trace[1858576594] 'read index received' (duration: 2.475976397s)","trace[1858576594] 'applied index is now lower than readState.Index' (duration: 145.940175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:25.062757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.527967914s","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-25T02:08:25.062802Z","caller":"traceutil/trace.go:171","msg":"trace[1806191404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"2.528051208s","start":"2026-02-25T02:08:22.534739Z","end":"2026-02-25T02:08:25.06279Z","steps":["trace[1806191404] 'agreement among raft nodes before linearized reading' (duration: 2.527860891s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.06284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:22.534725Z","time spent":"2.52810703s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:08:25.062868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.486006308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T02:08:25.062904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.104192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:25.062936Z","caller":"traceutil/trace.go:171","msg":"trace[439869055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"2.48609691s","start":"2026-02-25T02:08:22.576825Z","end":"2026-02-25T02:08:25.062922Z","steps":["trace[439869055] 'agreement among raft nodes before linearized reading' (duration: 2.485916334s)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:25.062954Z","caller":"traceutil/trace.go:171","msg":"trace[1107368319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"622.190535ms","start":"2026-02-25T02:08:24.440747Z","end":"2026-02-25T02:08:25.062937Z","steps":["trace[1107368319] 'agreement among raft nodes before linearized reading' (duration: 622.108602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.062997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:24.44073Z","time spent":"622.259528ms","remote":"127.0.0.1:42114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:08:25.062979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.487597586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T02:08:25.06296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:22.576819Z","time spent":"2.486135773s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:08:25.063066Z","caller":"traceutil/trace.go:171","msg":"trace[518381397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"2.487711811s","start":"2026-02-25T02:08:22.575331Z","end":"2026-02-25T02:08:25.063043Z","steps":["trace[518381397] 'agreement among raft nodes before linearized reading' (duration: 2.487443011s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.063065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.490895638s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T02:08:25.063109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:22.575323Z","time spent":"2.487779133s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T02:08:25.063133Z","caller":"traceutil/trace.go:171","msg":"trace[11183791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"2.491003712s","start":"2026-02-25T02:08:22.572114Z","end":"2026-02-25T02:08:25.063118Z","steps":["trace[11183791] 'agreement among raft nodes before linearized reading' (duration: 2.490714942s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.063169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:22.572102Z","time spent":"2.491061134s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:25.063851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.487143998s","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-25T02:08:25.063909Z","caller":"traceutil/trace.go:171","msg":"trace[1737015162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:631; }","duration":"2.487220811s","start":"2026-02-25T02:08:22.576673Z","end":"2026-02-25T02:08:25.063894Z","steps":["trace[1737015162] 'agreement among raft nodes before linearized reading' (duration: 2.486135502s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.063947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:22.576666Z","time spent":"2.487272413s","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T02:08:25.620673Z","caller":"traceutil/trace.go:171","msg":"trace[2079657029] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"551.457914ms","start":"2026-02-25T02:08:25.069197Z","end":"2026-02-25T02:08:25.620655Z","steps":["trace[2079657029] 'process raft request' (duration: 551.331079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.620785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.069173Z","time spent":"551.553847ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:25.620972Z","caller":"traceutil/trace.go:171","msg":"trace[1229162696] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"551.65404ms","start":"2026-02-25T02:08:25.069291Z","end":"2026-02-25T02:08:25.620945Z","steps":["trace[1229162696] 'process raft request' (duration: 551.334679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.62112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.069283Z","time spent":"551.751884ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:08:25.621417Z","caller":"traceutil/trace.go:171","msg":"trace[127594433] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"551.887449ms","start":"2026-02-25T02:08:25.069504Z","end":"2026-02-25T02:08:25.621391Z","steps":["trace[127594433] 'process raft request' (duration: 551.405392ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:08:25.621441Z","caller":"traceutil/trace.go:171","msg":"trace[701949122] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"550.356354ms","start":"2026-02-25T02:08:25.071073Z","end":"2026-02-25T02:08:25.621429Z","steps":["trace[701949122] 'process raft request' (duration: 550.247931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.621471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.069497Z","time spent":"551.947711ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:25.621501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.071055Z","time spent":"550.416326ms","remote":"127.0.0.1:42210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:08:25.621418Z","caller":"traceutil/trace.go:171","msg":"trace[1868726163] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:653; }","duration":"545.836232ms","start":"2026-02-25T02:08:25.075561Z","end":"2026-02-25T02:08:25.621397Z","steps":["trace[1868726163] 'read index received' (duration: 51.070249ms)","trace[1868726163] 'applied index is now lower than readState.Index' (duration: 494.764923ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:25.621551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.970067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:08:25.621592Z","caller":"traceutil/trace.go:171","msg":"trace[1755236069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"546.05255ms","start":"2026-02-25T02:08:25.07553Z","end":"2026-02-25T02:08:25.621582Z","steps":["trace[1755236069] 'agreement among raft nodes before linearized reading' (duration: 545.935496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.621635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.075522Z","time spent":"546.106022ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:08:25.622338Z","caller":"traceutil/trace.go:171","msg":"trace[720449596] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"551.731693ms","start":"2026-02-25T02:08:25.069434Z","end":"2026-02-25T02:08:25.621166Z","steps":["trace[720449596] 'process raft request' (duration: 551.385681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:25.622408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:25.069424Z","time spent":"552.950847ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:44.431136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.268826ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-25T02:08:52.453613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.762219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:08:52.453926Z","caller":"traceutil/trace.go:171","msg":"trace[1799512823] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"686.988964ms","start":"2026-02-25T02:08:51.766915Z","end":"2026-02-25T02:08:52.453903Z","steps":["trace[1799512823] 'read index received' (duration: 28.543997ms)","trace[1799512823] 'applied index is now lower than readState.Index' (duration: 658.443377ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:08:52.454122Z","caller":"traceutil/trace.go:171","msg":"trace[418491201] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"687.882689ms","start":"2026-02-25T02:08:51.76618Z","end":"2026-02-25T02:08:52.454063Z","steps":["trace[418491201] 'process raft request' (duration: 29.40392ms)","trace[418491201] 'compare' (duration: 656.805774ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:08:52.45437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:51.766166Z","time spent":"688.076723ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:08:52.454766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.828517ms","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-25T02:08:52.454849Z","caller":"traceutil/trace.go:171","msg":"trace[1371838863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"687.9527ms","start":"2026-02-25T02:08:51.766878Z","end":"2026-02-25T02:08:52.454831Z","steps":["trace[1371838863] 'agreement among raft nodes before linearized reading' (duration: 687.502358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:52.454888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:51.766867Z","time spent":"688.012931ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T02:08:52.454816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.947713ms","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-25T02:08:52.455011Z","caller":"traceutil/trace.go:171","msg":"trace[926892264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"687.168269ms","start":"2026-02-25T02:08:51.767824Z","end":"2026-02-25T02:08:52.454992Z","steps":["trace[926892264] 'agreement among raft nodes before linearized reading' (duration: 686.553113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:08:52.455113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:08:51.767815Z","time spent":"687.281022ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T02:09:34.490665Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256788016354854,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T02:09:34.707254Z","caller":"traceutil/trace.go:171","msg":"trace[736333705] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"932.912344ms","start":"2026-02-25T02:09:33.774319Z","end":"2026-02-25T02:09:34.707231Z","steps":["trace[736333705] 'process raft request' (duration: 932.777141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:34.707389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:33.774297Z","time spent":"933.027366ms","remote":"127.0.0.1:42288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:09:34.710039Z","caller":"traceutil/trace.go:171","msg":"trace[581477108] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:968; }","duration":"720.360508ms","start":"2026-02-25T02:09:33.989653Z","end":"2026-02-25T02:09:34.710013Z","steps":["trace[581477108] 'read index received' (duration: 717.423645ms)","trace[581477108] 'applied index is now lower than readState.Index' (duration: 2.935373ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:09:34.710293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.626213ms","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-25T02:09:34.710337Z","caller":"traceutil/trace.go:171","msg":"trace[2087219420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:932; }","duration":"720.706345ms","start":"2026-02-25T02:09:33.989618Z","end":"2026-02-25T02:09:34.710324Z","steps":["trace[2087219420] 'agreement among raft nodes before linearized reading' (duration: 720.550932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:34.710384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:33.989606Z","time spent":"720.770186ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:09:34.710667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.675145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:09:34.710744Z","caller":"traceutil/trace.go:171","msg":"trace[814201756] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:932; }","duration":"278.798857ms","start":"2026-02-25T02:09:34.431926Z","end":"2026-02-25T02:09:34.710725Z","steps":["trace[814201756] 'agreement among raft nodes before linearized reading' (duration: 278.668625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:34.710993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.816452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:09:34.711044Z","caller":"traceutil/trace.go:171","msg":"trace[1903527903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"270.910034ms","start":"2026-02-25T02:09:34.440122Z","end":"2026-02-25T02:09:34.711032Z","steps":["trace[1903527903] 'agreement among raft nodes before linearized reading' (duration: 270.836512ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:47.668383Z","caller":"traceutil/trace.go:171","msg":"trace[457610685] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"111.485006ms","start":"2026-02-25T02:09:47.556871Z","end":"2026-02-25T02:09:47.668356Z","steps":["trace[457610685] 'process raft request' (duration: 111.353634ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:49.182608Z","caller":"traceutil/trace.go:171","msg":"trace[287770769] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"387.194773ms","start":"2026-02-25T02:09:48.795387Z","end":"2026-02-25T02:09:49.182581Z","steps":["trace[287770769] 'process raft request' (duration: 387.118232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:49.182751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:48.795366Z","time spent":"387.314785ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:09:49.182849Z","caller":"traceutil/trace.go:171","msg":"trace[1281235223] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1052; }","duration":"144.213813ms","start":"2026-02-25T02:09:49.038617Z","end":"2026-02-25T02:09:49.18283Z","steps":["trace[1281235223] 'read index received' (duration: 143.748746ms)","trace[1281235223] 'applied index is now lower than readState.Index' (duration: 464.127µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:09:49.182966Z","caller":"traceutil/trace.go:171","msg":"trace[10798015] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"389.289537ms","start":"2026-02-25T02:09:48.793663Z","end":"2026-02-25T02:09:49.182952Z","steps":["trace[10798015] 'process raft request' (duration: 388.681537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:49.183011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.398346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.102\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T02:09:49.183044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:48.793648Z","time spent":"389.362189ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:09:49.183049Z","caller":"traceutil/trace.go:171","msg":"trace[1786346030] range","detail":"{range_begin:/registry/masterleases/199.204.45.102; range_end:; response_count:1; response_revision:1014; }","duration":"144.554819ms","start":"2026-02-25T02:09:49.038486Z","end":"2026-02-25T02:09:49.18304Z","steps":["trace[1786346030] 'agreement among raft nodes before linearized reading' (duration: 144.418296ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:09:49.183195Z","caller":"traceutil/trace.go:171","msg":"trace[373662941] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"387.651061ms","start":"2026-02-25T02:09:48.795534Z","end":"2026-02-25T02:09:49.183185Z","steps":["trace[373662941] 'process raft request' (duration: 387.007841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:09:49.183264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:09:48.795508Z","time spent":"387.718102ms","remote":"127.0.0.1:42382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}