{"level":"warn","ts":"2026-06-18T01:00:24.041837Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T01:00:24.041981Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.128:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.128:2380","--initial-cluster=instance=https://199.19.213.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.128: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-06-18T01:00:24.042083Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-18T01:00:24.0421Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-06-18T01:00:24.04212Z","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-06-18T01:00:24.043018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"]} {"level":"info","ts":"2026-06-18T01:00:24.043108Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.128: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-06-18T01:00:24.046905Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.484868ms"} {"level":"info","ts":"2026-06-18T01:00:24.051738Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a463a6aa9b462de2","cluster-id":"cd3d14b8ed85e2f2"} {"level":"info","ts":"2026-06-18T01:00:24.051799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=()"} {"level":"info","ts":"2026-06-18T01:00:24.051821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 0"} {"level":"info","ts":"2026-06-18T01:00:24.051839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a463a6aa9b462de2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-18T01:00:24.051847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 1"} {"level":"info","ts":"2026-06-18T01:00:24.051887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"warn","ts":"2026-06-18T01:00:24.056817Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-18T01:00:24.057929Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-18T01:00:24.058779Z","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-06-18T01:00:24.060708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a463a6aa9b462de2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-18T01:00:24.060923Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a463a6aa9b462de2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-18T01:00:24.061152Z","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-06-18T01:00:24.061289Z","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-06-18T01:00:24.061309Z","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-06-18T01:00:24.064319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"info","ts":"2026-06-18T01:00:24.06496Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","added-peer-id":"a463a6aa9b462de2","added-peer-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-06-18T01:00:24.066268Z","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-06-18T01:00:24.066475Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a463a6aa9b462de2","initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-18T01:00:24.066517Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-18T01:00:24.066445Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-06-18T01:00:24.06654Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-06-18T01:00:24.252131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 is starting a new election at term 1"} {"level":"info","ts":"2026-06-18T01:00:24.252201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-18T01:00:24.252227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgPreVoteResp from a463a6aa9b462de2 at term 1"} {"level":"info","ts":"2026-06-18T01:00:24.25225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became candidate at term 2"} {"level":"info","ts":"2026-06-18T01:00:24.252284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgVoteResp from a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-06-18T01:00:24.252302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became leader at term 2"} {"level":"info","ts":"2026-06-18T01:00:24.252317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a463a6aa9b462de2 elected leader a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-06-18T01:00:24.253418Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T01:00:24.255007Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T01:00:24.255153Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T01:00:24.255213Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-18T01:00:24.255272Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a463a6aa9b462de2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.128:2379]}","request-path":"/0/members/a463a6aa9b462de2/attributes","cluster-id":"cd3d14b8ed85e2f2","publish-timeout":"7s"} {"level":"info","ts":"2026-06-18T01:00:24.255328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T01:00:24.255391Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-18T01:00:24.255562Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-18T01:00:24.255627Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-18T01:00:24.257653Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-18T01:00:24.264716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.128:2379"} {"level":"info","ts":"2026-06-18T01:00:35.059216Z","caller":"traceutil/trace.go:171","msg":"trace[570607079] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"101.760738ms","start":"2026-06-18T01:00:34.957415Z","end":"2026-06-18T01:00:35.059176Z","steps":["trace[570607079] 'process raft request' (duration: 101.577942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:00:40.826567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.736914ms","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-06-18T01:00:40.826713Z","caller":"traceutil/trace.go:171","msg":"trace[1174027673] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:409; }","duration":"175.997531ms","start":"2026-06-18T01:00:40.65069Z","end":"2026-06-18T01:00:40.826687Z","steps":["trace[1174027673] 'range keys from in-memory index tree' (duration: 175.631751ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:00:41.228385Z","caller":"traceutil/trace.go:171","msg":"trace[345968310] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"197.858449ms","start":"2026-06-18T01:00:41.030504Z","end":"2026-06-18T01:00:41.228363Z","steps":["trace[345968310] 'read index received' (duration: 197.657833ms)","trace[345968310] 'applied index is now lower than readState.Index' (duration: 200.006µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:00:41.228504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.989062ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:00:41.228578Z","caller":"traceutil/trace.go:171","msg":"trace[103808491] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:410; }","duration":"198.076214ms","start":"2026-06-18T01:00:41.030492Z","end":"2026-06-18T01:00:41.228568Z","steps":["trace[103808491] 'agreement among raft nodes before linearized reading' (duration: 197.980042ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:00:41.228537Z","caller":"traceutil/trace.go:171","msg":"trace[880847627] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"396.556799ms","start":"2026-06-18T01:00:40.831927Z","end":"2026-06-18T01:00:41.228484Z","steps":["trace[880847627] 'process raft request' (duration: 396.327554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:00:41.228609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.856799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-06-18T01:00:41.228693Z","caller":"traceutil/trace.go:171","msg":"trace[890046322] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:410; }","duration":"139.975511ms","start":"2026-06-18T01:00:41.088685Z","end":"2026-06-18T01:00:41.228661Z","steps":["trace[890046322] 'agreement among raft nodes before linearized reading' (duration: 139.818498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:00:41.229322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:00:40.831891Z","time spent":"396.755124ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:00:42.267119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306379728039212697,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T01:00:42.379927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.143422877s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T01:00:42.380284Z","caller":"traceutil/trace.go:171","msg":"trace[1734891091] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"613.226137ms","start":"2026-06-18T01:00:41.767039Z","end":"2026-06-18T01:00:42.380265Z","steps":["trace[1734891091] 'read index received' (duration: 613.038883ms)","trace[1734891091] 'applied index is now lower than readState.Index' (duration: 186.274µs)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:00:42.380495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.284793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:00:42.380573Z","caller":"traceutil/trace.go:171","msg":"trace[1558505433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"603.408446ms","start":"2026-06-18T01:00:41.77714Z","end":"2026-06-18T01:00:42.380548Z","steps":["trace[1558505433] 'agreement among raft nodes before linearized reading' (duration: 603.294773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:00:42.380633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:00:41.777119Z","time spent":"603.50038ms","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T01:00:42.380638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.105464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-18T01:00:42.380729Z","caller":"traceutil/trace.go:171","msg":"trace[313492374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"314.254829ms","start":"2026-06-18T01:00:42.066457Z","end":"2026-06-18T01:00:42.380712Z","steps":["trace[313492374] 'agreement among raft nodes before linearized reading' (duration: 314.031612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:00:42.380778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:00:42.066436Z","time spent":"314.33194ms","remote":"127.0.0.1:52214","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-06-18T01:00:42.380512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.475124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-18T01:00:42.380649Z","caller":"traceutil/trace.go:171","msg":"trace[1292755017] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.144241138s","start":"2026-06-18T01:00:41.236383Z","end":"2026-06-18T01:00:42.380624Z","steps":["trace[1292755017] 'process raft request' (duration: 1.143762465s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:00:42.381111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:00:41.236356Z","time spent":"1.144682628s","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:00:42.381123Z","caller":"traceutil/trace.go:171","msg":"trace[1126053873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"614.045818ms","start":"2026-06-18T01:00:41.76697Z","end":"2026-06-18T01:00:42.381016Z","steps":["trace[1126053873] 'agreement among raft nodes before linearized reading' (duration: 613.449073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:00:42.381335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:00:41.766942Z","time spent":"614.372816ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-18T01:01:05.453042Z","caller":"traceutil/trace.go:171","msg":"trace[673058458] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"243.437082ms","start":"2026-06-18T01:01:05.209577Z","end":"2026-06-18T01:01:05.453014Z","steps":["trace[673058458] 'process raft request' (duration: 235.776397ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:05.453139Z","caller":"traceutil/trace.go:171","msg":"trace[1641793495] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:580; }","duration":"124.23325ms","start":"2026-06-18T01:01:05.328878Z","end":"2026-06-18T01:01:05.453112Z","steps":["trace[1641793495] 'read index received' (duration: 116.491213ms)","trace[1641793495] 'applied index is now lower than readState.Index' (duration: 7.741177ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T01:01:05.453227Z","caller":"traceutil/trace.go:171","msg":"trace[513288323] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"241.439141ms","start":"2026-06-18T01:01:05.211776Z","end":"2026-06-18T01:01:05.453215Z","steps":["trace[513288323] 'process raft request' (duration: 241.111362ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:05.453303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.419325ms","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-06-18T01:01:05.453312Z","caller":"traceutil/trace.go:171","msg":"trace[2141519347] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"191.552568ms","start":"2026-06-18T01:01:05.261727Z","end":"2026-06-18T01:01:05.45328Z","steps":["trace[2141519347] 'process raft request' (duration: 191.275911ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:05.453346Z","caller":"traceutil/trace.go:171","msg":"trace[1803889665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"124.515007ms","start":"2026-06-18T01:01:05.328819Z","end":"2026-06-18T01:01:05.453334Z","steps":["trace[1803889665] 'agreement among raft nodes before linearized reading' (duration: 124.391844ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:05.981434Z","caller":"traceutil/trace.go:171","msg":"trace[1354011306] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"477.005151ms","start":"2026-06-18T01:01:05.504406Z","end":"2026-06-18T01:01:05.981411Z","steps":["trace[1354011306] 'process raft request' (duration: 461.419404ms)","trace[1354011306] 'compare' (duration: 15.445644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:01:05.981544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:05.504388Z","time spent":"477.098954ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:01:05.981612Z","caller":"traceutil/trace.go:171","msg":"trace[1199498650] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"462.554223ms","start":"2026-06-18T01:01:05.519044Z","end":"2026-06-18T01:01:05.981599Z","steps":["trace[1199498650] 'process raft request' (duration: 462.317556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:05.981677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:05.519029Z","time spent":"462.614155ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:01:05.982264Z","caller":"traceutil/trace.go:171","msg":"trace[2032942822] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:588; }","duration":"204.608481ms","start":"2026-06-18T01:01:05.777645Z","end":"2026-06-18T01:01:05.982253Z","steps":["trace[2032942822] 'read index received' (duration: 188.205872ms)","trace[2032942822] 'applied index is now lower than readState.Index' (duration: 16.401999ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:01:05.982352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.707593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:01:05.982379Z","caller":"traceutil/trace.go:171","msg":"trace[1926154691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"204.769355ms","start":"2026-06-18T01:01:05.777602Z","end":"2026-06-18T01:01:05.982371Z","steps":["trace[1926154691] 'agreement among raft nodes before linearized reading' (duration: 204.688883ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:05.982536Z","caller":"traceutil/trace.go:171","msg":"trace[1825733093] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"457.285138ms","start":"2026-06-18T01:01:05.525242Z","end":"2026-06-18T01:01:05.982527Z","steps":["trace[1825733093] 'process raft request' (duration: 456.307173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:05.990321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:05.525227Z","time spent":"465.040856ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T01:01:05.985872Z","caller":"traceutil/trace.go:171","msg":"trace[1305722224] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"442.749067ms","start":"2026-06-18T01:01:05.539761Z","end":"2026-06-18T01:01:05.98251Z","steps":["trace[1305722224] 'process raft request' (duration: 442.205322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:05.990546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:05.539739Z","time spent":"450.781661ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:01:11.614163Z","caller":"traceutil/trace.go:171","msg":"trace[1613387734] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"109.476103ms","start":"2026-06-18T01:01:11.504664Z","end":"2026-06-18T01:01:11.61414Z","steps":["trace[1613387734] 'process raft request' (duration: 109.028681ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:12.378455Z","caller":"traceutil/trace.go:171","msg":"trace[380668792] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"501.933647ms","start":"2026-06-18T01:01:11.876282Z","end":"2026-06-18T01:01:12.378215Z","steps":["trace[380668792] 'process raft request' (duration: 498.557261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:12.378632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:11.876258Z","time spent":"502.288238ms","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:01:12.3969Z","caller":"traceutil/trace.go:171","msg":"trace[691606298] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"217.230903ms","start":"2026-06-18T01:01:12.179633Z","end":"2026-06-18T01:01:12.396864Z","steps":["trace[691606298] 'process raft request' (duration: 215.54121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:13.423017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.551661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T01:01:13.423184Z","caller":"traceutil/trace.go:171","msg":"trace[777129491] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"779.210442ms","start":"2026-06-18T01:01:12.643955Z","end":"2026-06-18T01:01:13.423165Z","steps":["trace[777129491] 'process raft request' (duration: 180.26445ms)","trace[777129491] 'compare' (duration: 598.420988ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:01:13.423253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:12.643938Z","time spent":"779.282674ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:01:13.423477Z","caller":"traceutil/trace.go:171","msg":"trace[1106870291] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"779.425728ms","start":"2026-06-18T01:01:12.644014Z","end":"2026-06-18T01:01:13.42344Z","steps":["trace[1106870291] 'process raft request' (duration: 779.10463ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:13.42357Z","caller":"traceutil/trace.go:171","msg":"trace[357311151] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"657.416334ms","start":"2026-06-18T01:01:12.766138Z","end":"2026-06-18T01:01:13.423554Z","steps":["trace[357311151] 'read index received' (duration: 58.390549ms)","trace[357311151] 'applied index is now lower than readState.Index' (duration: 599.024575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:01:13.423634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.490716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T01:01:13.423639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:12.643997Z","time spent":"779.556141ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:01:13.423659Z","caller":"traceutil/trace.go:171","msg":"trace[1891061323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"657.558408ms","start":"2026-06-18T01:01:12.766092Z","end":"2026-06-18T01:01:13.42365Z","steps":["trace[1891061323] 'agreement among raft nodes before linearized reading' (duration: 657.509126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:13.423693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:12.766073Z","time spent":"657.61355ms","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T01:01:13.424682Z","caller":"traceutil/trace.go:171","msg":"trace[1564424741] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"529.412768ms","start":"2026-06-18T01:01:12.895245Z","end":"2026-06-18T01:01:13.424658Z","steps":["trace[1564424741] 'process raft request' (duration: 528.508545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:13.424787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:12.895215Z","time spent":"529.515661ms","remote":"127.0.0.1:52014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:01:14.377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.608286ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-18T01:01:14.377118Z","caller":"traceutil/trace.go:171","msg":"trace[1994124013] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"952.338001ms","start":"2026-06-18T01:01:13.424754Z","end":"2026-06-18T01:01:14.377092Z","steps":["trace[1994124013] 'read index received' (duration: 52.041µs)","trace[1994124013] 'applied index is now lower than readState.Index' (duration: 952.28426ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:01:14.377397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.628178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:01:14.377444Z","caller":"traceutil/trace.go:171","msg":"trace[252756439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"952.72164ms","start":"2026-06-18T01:01:13.42471Z","end":"2026-06-18T01:01:14.377432Z","steps":["trace[252756439] 'agreement among raft nodes before linearized reading' (duration: 952.634298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:14.377477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:13.424692Z","time spent":"952.778491ms","remote":"127.0.0.1:51950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T01:01:14.878177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306379728039213152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T01:01:15.37838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306379728039213152,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T01:01:15.435899Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.954135132s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T01:01:15.436211Z","caller":"traceutil/trace.go:171","msg":"trace[1062925114] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"1.059031081s","start":"2026-06-18T01:01:14.377158Z","end":"2026-06-18T01:01:15.436189Z","steps":["trace[1062925114] 'read index received' (duration: 1.058849707s)","trace[1062925114] 'applied index is now lower than readState.Index' (duration: 180.744µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T01:01:15.436254Z","caller":"traceutil/trace.go:171","msg":"trace[282484749] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.954588872s","start":"2026-06-18T01:01:13.481646Z","end":"2026-06-18T01:01:15.436235Z","steps":["trace[282484749] 'process raft request' (duration: 1.954405428s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:15.436347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049659234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T01:01:15.436356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:13.481624Z","time spent":"1.954671026s","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T01:01:15.436381Z","caller":"traceutil/trace.go:171","msg":"trace[1705351904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"1.049734285s","start":"2026-06-18T01:01:14.386635Z","end":"2026-06-18T01:01:15.43637Z","steps":["trace[1705351904] 'agreement among raft nodes before linearized reading' (duration: 1.049682584s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:15.436402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:14.386612Z","time spent":"1.049784566s","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T01:01:15.436403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919729994s","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-06-18T01:01:15.436432Z","caller":"traceutil/trace.go:171","msg":"trace[129755185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"1.919810736s","start":"2026-06-18T01:01:13.516612Z","end":"2026-06-18T01:01:15.436422Z","steps":["trace[129755185] 'agreement among raft nodes before linearized reading' (duration: 1.919676463s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:15.436458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:13.516583Z","time spent":"1.919868008s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T01:01:15.436582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.937582ms","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-06-18T01:01:15.436624Z","caller":"traceutil/trace.go:171","msg":"trace[252326391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"793.020915ms","start":"2026-06-18T01:01:14.643593Z","end":"2026-06-18T01:01:15.436614Z","steps":["trace[252326391] 'agreement among raft nodes before linearized reading' (duration: 792.935863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:15.436653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:14.643566Z","time spent":"793.078246ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-18T01:01:15.546786Z","caller":"traceutil/trace.go:171","msg":"trace[1920003511] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"106.132458ms","start":"2026-06-18T01:01:15.440635Z","end":"2026-06-18T01:01:15.546767Z","steps":["trace[1920003511] 'process raft request' (duration: 101.595013ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:15.546941Z","caller":"traceutil/trace.go:171","msg":"trace[1146924850] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"106.045727ms","start":"2026-06-18T01:01:15.440889Z","end":"2026-06-18T01:01:15.546935Z","steps":["trace[1146924850] 'process raft request' (duration: 105.767369ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:15.547037Z","caller":"traceutil/trace.go:171","msg":"trace[505009481] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"105.517243ms","start":"2026-06-18T01:01:15.441509Z","end":"2026-06-18T01:01:15.547026Z","steps":["trace[505009481] 'process raft request' (duration: 105.283476ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:15.54711Z","caller":"traceutil/trace.go:171","msg":"trace[705916530] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"103.724586ms","start":"2026-06-18T01:01:15.443361Z","end":"2026-06-18T01:01:15.547085Z","steps":["trace[705916530] 'process raft request' (duration: 103.551792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:15.547229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.034195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-06-18T01:01:15.547257Z","caller":"traceutil/trace.go:171","msg":"trace[1383715492] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:650; }","duration":"104.100116ms","start":"2026-06-18T01:01:15.44315Z","end":"2026-06-18T01:01:15.54725Z","steps":["trace[1383715492] 'agreement among raft nodes before linearized reading' (duration: 104.038005ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:15.547232Z","caller":"traceutil/trace.go:171","msg":"trace[801896527] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:669; }","duration":"103.86229ms","start":"2026-06-18T01:01:15.443182Z","end":"2026-06-18T01:01:15.547044Z","steps":["trace[801896527] 'read index received' (duration: 99.093199ms)","trace[801896527] 'applied index is now lower than readState.Index' (duration: 4.767781ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:01:15.547572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.04899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:01:15.547649Z","caller":"traceutil/trace.go:171","msg":"trace[1390925261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"103.160473ms","start":"2026-06-18T01:01:15.444475Z","end":"2026-06-18T01:01:15.547636Z","steps":["trace[1390925261] 'agreement among raft nodes before linearized reading' (duration: 103.018828ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:17.415729Z","caller":"traceutil/trace.go:171","msg":"trace[370500887] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"211.343593ms","start":"2026-06-18T01:01:17.20433Z","end":"2026-06-18T01:01:17.415673Z","steps":["trace[370500887] 'process raft request' (duration: 211.138558ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:20.068044Z","caller":"traceutil/trace.go:171","msg":"trace[2132484908] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"141.233822ms","start":"2026-06-18T01:01:19.926775Z","end":"2026-06-18T01:01:20.068009Z","steps":["trace[2132484908] 'process raft request' (duration: 140.625568ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:21.087432Z","caller":"traceutil/trace.go:171","msg":"trace[1943389222] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"319.835481ms","start":"2026-06-18T01:01:20.767567Z","end":"2026-06-18T01:01:21.087402Z","steps":["trace[1943389222] 'read index received' (duration: 319.373459ms)","trace[1943389222] 'applied index is now lower than readState.Index' (duration: 460.802µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T01:01:21.0875Z","caller":"traceutil/trace.go:171","msg":"trace[1928820885] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"463.25605ms","start":"2026-06-18T01:01:20.624203Z","end":"2026-06-18T01:01:21.087459Z","steps":["trace[1928820885] 'process raft request' (duration: 462.84408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:21.08771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.123698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T01:01:21.087744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:20.624183Z","time spent":"463.463205ms","remote":"127.0.0.1:51984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-18T01:01:21.087797Z","caller":"traceutil/trace.go:171","msg":"trace[203931981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"320.253081ms","start":"2026-06-18T01:01:20.76751Z","end":"2026-06-18T01:01:21.087763Z","steps":["trace[203931981] 'agreement among raft nodes before linearized reading' (duration: 320.040296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:21.087849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:20.767487Z","time spent":"320.348854ms","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T01:01:21.088153Z","caller":"traceutil/trace.go:171","msg":"trace[186497638] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"162.544828ms","start":"2026-06-18T01:01:20.92557Z","end":"2026-06-18T01:01:21.088115Z","steps":["trace[186497638] 'process raft request' (duration: 162.471996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:21.088381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.448866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fb722\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-06-18T01:01:21.088415Z","caller":"traceutil/trace.go:171","msg":"trace[245770555] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fb722; range_end:; response_count:1; response_revision:730; }","duration":"164.529908ms","start":"2026-06-18T01:01:20.923875Z","end":"2026-06-18T01:01:21.088405Z","steps":["trace[245770555] 'agreement among raft nodes before linearized reading' (duration: 164.351214ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:01:21.088482Z","caller":"traceutil/trace.go:171","msg":"trace[2124084921] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"185.995936ms","start":"2026-06-18T01:01:20.902452Z","end":"2026-06-18T01:01:21.088448Z","steps":["trace[2124084921] 'process raft request' (duration: 185.424912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:21.088531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.247242ms","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-06-18T01:01:21.089074Z","caller":"traceutil/trace.go:171","msg":"trace[1224367286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"262.827497ms","start":"2026-06-18T01:01:20.826226Z","end":"2026-06-18T01:01:21.089053Z","steps":["trace[1224367286] 'agreement among raft nodes before linearized reading' (duration: 262.147979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:21.089234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.091332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-06-18T01:01:21.089337Z","caller":"traceutil/trace.go:171","msg":"trace[22850067] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:730; }","duration":"290.198513ms","start":"2026-06-18T01:01:20.79908Z","end":"2026-06-18T01:01:21.089278Z","steps":["trace[22850067] 'agreement among raft nodes before linearized reading' (duration: 289.012293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:31.648028Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306379728039213473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T01:01:32.149051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306379728039213473,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T01:01:32.189714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4237933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:01:32.189776Z","caller":"traceutil/trace.go:171","msg":"trace[169765294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"1.423904272s","start":"2026-06-18T01:01:30.765854Z","end":"2026-06-18T01:01:32.189758Z","steps":["trace[169765294] 'range keys from in-memory index tree' (duration: 1.423561614s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.189768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210142038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2cr87\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-06-18T01:01:32.189803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:30.765829Z","time spent":"1.423967404s","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T01:01:32.189837Z","caller":"traceutil/trace.go:171","msg":"trace[2014386430] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2cr87; range_end:; response_count:1; response_revision:807; }","duration":"1.210244471s","start":"2026-06-18T01:01:30.979575Z","end":"2026-06-18T01:01:32.189819Z","steps":["trace[2014386430] 'range keys from in-memory index tree' (duration: 1.209990884s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.189868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:30.97956Z","time spent":"1.210300382s","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2cr87\" "} {"level":"warn","ts":"2026-06-18T01:01:32.190088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069222502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:01:32.190225Z","caller":"traceutil/trace.go:171","msg":"trace[965404685] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:807; }","duration":"1.070203568s","start":"2026-06-18T01:01:31.119996Z","end":"2026-06-18T01:01:32.1902Z","steps":["trace[965404685] 'count revisions from in-memory index tree' (duration: 1.06912196s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.190638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.119972Z","time spent":"1.070651168s","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-18T01:01:32.195736Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070422743s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T01:01:32.196374Z","caller":"traceutil/trace.go:171","msg":"trace[22264319] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.071160822s","start":"2026-06-18T01:01:31.125172Z","end":"2026-06-18T01:01:32.196332Z","steps":["trace[22264319] 'process raft request' (duration: 1.071003258s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.196553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.125148Z","time spent":"1.071318876s","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:01:32.200811Z","caller":"traceutil/trace.go:171","msg":"trace[1222345757] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"1.052822554s","start":"2026-06-18T01:01:31.147969Z","end":"2026-06-18T01:01:32.200791Z","steps":["trace[1222345757] 'read index received' (duration: 1.048129214s)","trace[1222345757] 'applied index is now lower than readState.Index' (duration: 4.69247ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T01:01:32.200896Z","caller":"traceutil/trace.go:171","msg":"trace[1982187099] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"780.365762ms","start":"2026-06-18T01:01:31.420523Z","end":"2026-06-18T01:01:32.200889Z","steps":["trace[1982187099] 'process raft request' (duration: 780.164686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.200986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.420499Z","time spent":"780.417493ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:01:32.201152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053187542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-18T01:01:32.201182Z","caller":"traceutil/trace.go:171","msg":"trace[1468720565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"1.053247764s","start":"2026-06-18T01:01:31.147924Z","end":"2026-06-18T01:01:32.201172Z","steps":["trace[1468720565] 'agreement among raft nodes before linearized reading' (duration: 1.053148602s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.201202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.147903Z","time spent":"1.053294845s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T01:01:32.204116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.617506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T01:01:32.204203Z","caller":"traceutil/trace.go:171","msg":"trace[747434569] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:810; }","duration":"689.76216ms","start":"2026-06-18T01:01:31.514422Z","end":"2026-06-18T01:01:32.204184Z","steps":["trace[747434569] 'agreement among raft nodes before linearized reading' (duration: 689.608376ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.204259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.514402Z","time spent":"689.847012ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-06-18T01:01:32.204548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.466937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-18T01:01:32.204583Z","caller":"traceutil/trace.go:171","msg":"trace[980371790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"581.535168ms","start":"2026-06-18T01:01:31.623039Z","end":"2026-06-18T01:01:32.204574Z","steps":["trace[980371790] 'agreement among raft nodes before linearized reading' (duration: 581.412845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.20461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.623022Z","time spent":"581.58269ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T01:01:32.204797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.968965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-18T01:01:32.204821Z","caller":"traceutil/trace.go:171","msg":"trace[1714223468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"583.011075ms","start":"2026-06-18T01:01:31.621805Z","end":"2026-06-18T01:01:32.204816Z","steps":["trace[1714223468] 'agreement among raft nodes before linearized reading' (duration: 582.938143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.204838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.621798Z","time spent":"583.036036ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T01:01:32.204862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.268309ms","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-06-18T01:01:32.204913Z","caller":"traceutil/trace.go:171","msg":"trace[40035861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:810; }","duration":"586.364142ms","start":"2026-06-18T01:01:31.618538Z","end":"2026-06-18T01:01:32.204902Z","steps":["trace[40035861] 'agreement among raft nodes before linearized reading' (duration: 586.232838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.204939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.618518Z","time spent":"586.415743ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T01:01:32.205079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.372816ms","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-06-18T01:01:32.205121Z","caller":"traceutil/trace.go:171","msg":"trace[696062703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"583.438477ms","start":"2026-06-18T01:01:31.621672Z","end":"2026-06-18T01:01:32.205111Z","steps":["trace[696062703] 'agreement among raft nodes before linearized reading' (duration: 583.318274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:01:32.205149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:01:31.62166Z","time spent":"583.482629ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-18T01:02:07.379804Z","caller":"traceutil/trace.go:171","msg":"trace[1157593818] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"203.25347ms","start":"2026-06-18T01:02:07.176526Z","end":"2026-06-18T01:02:07.379779Z","steps":["trace[1157593818] 'process raft request' (duration: 203.086095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:08.432294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.635771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-18T01:02:08.432721Z","caller":"traceutil/trace.go:171","msg":"trace[1518688268] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:1025; }","duration":"564.113073ms","start":"2026-06-18T01:02:07.868586Z","end":"2026-06-18T01:02:08.432699Z","steps":["trace[1518688268] 'range keys from in-memory index tree' (duration: 563.383425ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:08.43277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:07.868566Z","time spent":"564.190726ms","remote":"127.0.0.1:51966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.128\" "} {"level":"info","ts":"2026-06-18T01:02:08.479855Z","caller":"traceutil/trace.go:171","msg":"trace[1991818957] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"266.859958ms","start":"2026-06-18T01:02:08.212906Z","end":"2026-06-18T01:02:08.479766Z","steps":["trace[1991818957] 'process raft request' (duration: 266.618551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:08.495836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.788853ms","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-06-18T01:02:08.495899Z","caller":"traceutil/trace.go:171","msg":"trace[592958673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"100.907636ms","start":"2026-06-18T01:02:08.394977Z","end":"2026-06-18T01:02:08.495885Z","steps":["trace[592958673] 'agreement among raft nodes before linearized reading' (duration: 100.683229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:08.496208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.606064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-18T01:02:08.496312Z","caller":"traceutil/trace.go:171","msg":"trace[1004330081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"101.747218ms","start":"2026-06-18T01:02:08.394539Z","end":"2026-06-18T01:02:08.496287Z","steps":["trace[1004330081] 'agreement among raft nodes before linearized reading' (duration: 101.147782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:08.496336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.077486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-18T01:02:08.496396Z","caller":"traceutil/trace.go:171","msg":"trace[1423921649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"102.162598ms","start":"2026-06-18T01:02:08.394218Z","end":"2026-06-18T01:02:08.496381Z","steps":["trace[1423921649] 'agreement among raft nodes before linearized reading' (duration: 101.348787ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:38.156424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.657928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:02:38.156515Z","caller":"traceutil/trace.go:171","msg":"trace[2005877415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"389.808262ms","start":"2026-06-18T01:02:37.766687Z","end":"2026-06-18T01:02:38.156495Z","steps":["trace[2005877415] 'range keys from in-memory index tree' (duration: 389.549374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:38.156596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:37.766654Z","time spent":"389.930715ms","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T01:02:38.156646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.048452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-18T01:02:38.156727Z","caller":"traceutil/trace.go:171","msg":"trace[1957145404] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:1124; }","duration":"286.175165ms","start":"2026-06-18T01:02:37.870534Z","end":"2026-06-18T01:02:38.156709Z","steps":["trace[1957145404] 'range keys from in-memory index tree' (duration: 285.767524ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:38.697908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:38.158548Z","time spent":"539.351786ms","remote":"127.0.0.1:51966","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-18T01:02:39.079589Z","caller":"traceutil/trace.go:171","msg":"trace[873416374] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"379.747919ms","start":"2026-06-18T01:02:38.699804Z","end":"2026-06-18T01:02:39.079552Z","steps":["trace[873416374] 'process raft request' (duration: 306.251692ms)","trace[873416374] 'compare' (duration: 73.319943ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:02:39.080043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:38.699774Z","time spent":"379.909963ms","remote":"127.0.0.1:51966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:39.095517Z","caller":"traceutil/trace.go:171","msg":"trace[2070343678] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"392.427822ms","start":"2026-06-18T01:02:38.703078Z","end":"2026-06-18T01:02:39.095506Z","steps":["trace[2070343678] 'process raft request' (duration: 392.276607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:39.095648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:38.703073Z","time spent":"392.489483ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:39.095778Z","caller":"traceutil/trace.go:171","msg":"trace[615808340] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"392.632747ms","start":"2026-06-18T01:02:38.703125Z","end":"2026-06-18T01:02:39.095758Z","steps":["trace[615808340] 'process raft request' (duration: 392.276107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:39.095937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:38.70312Z","time spent":"392.76556ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:39.096034Z","caller":"traceutil/trace.go:171","msg":"trace[1125236091] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"391.62373ms","start":"2026-06-18T01:02:38.70439Z","end":"2026-06-18T01:02:39.096013Z","steps":["trace[1125236091] 'process raft request' (duration: 391.492867ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:02:39.096095Z","caller":"traceutil/trace.go:171","msg":"trace[1475163462] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"392.379989ms","start":"2026-06-18T01:02:38.703639Z","end":"2026-06-18T01:02:39.096019Z","steps":["trace[1475163462] 'process raft request' (duration: 392.01665ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:39.096181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:38.704382Z","time spent":"391.722212ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:39.096127Z","caller":"traceutil/trace.go:171","msg":"trace[1307517025] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"138.613085ms","start":"2026-06-18T01:02:38.957456Z","end":"2026-06-18T01:02:39.096069Z","steps":["trace[1307517025] 'process raft request' (duration: 138.517252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:39.096248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:38.703612Z","time spent":"392.547865ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:39.095468Z","caller":"traceutil/trace.go:171","msg":"trace[582692652] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"392.438782ms","start":"2026-06-18T01:02:38.703008Z","end":"2026-06-18T01:02:39.095447Z","steps":["trace[582692652] 'process raft request' (duration: 392.176005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:39.096573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:38.702995Z","time spent":"393.499739ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:02:39.913187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.675187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:02:39.913288Z","caller":"traceutil/trace.go:171","msg":"trace[596712741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1132; }","duration":"146.825501ms","start":"2026-06-18T01:02:39.766442Z","end":"2026-06-18T01:02:39.913268Z","steps":["trace[596712741] 'range keys from in-memory index tree' (duration: 146.569973ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:39.913418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.865023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:02:39.913544Z","caller":"traceutil/trace.go:171","msg":"trace[718867620] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1132; }","duration":"255.031387ms","start":"2026-06-18T01:02:39.658456Z","end":"2026-06-18T01:02:39.913487Z","steps":["trace[718867620] 'count revisions from in-memory index tree' (duration: 254.764981ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:02:41.265557Z","caller":"traceutil/trace.go:171","msg":"trace[1484949422] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"160.533292ms","start":"2026-06-18T01:02:41.105005Z","end":"2026-06-18T01:02:41.265538Z","steps":["trace[1484949422] 'process raft request' (duration: 160.506902ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:02:41.265601Z","caller":"traceutil/trace.go:171","msg":"trace[1916549779] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"160.630065ms","start":"2026-06-18T01:02:41.104939Z","end":"2026-06-18T01:02:41.265569Z","steps":["trace[1916549779] 'process raft request' (duration: 158.098938ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:02:41.265737Z","caller":"traceutil/trace.go:171","msg":"trace[863889773] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"160.79862ms","start":"2026-06-18T01:02:41.104931Z","end":"2026-06-18T01:02:41.265729Z","steps":["trace[863889773] 'process raft request' (duration: 160.527492ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:02:41.265843Z","caller":"traceutil/trace.go:171","msg":"trace[2110107228] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"160.873092ms","start":"2026-06-18T01:02:41.104955Z","end":"2026-06-18T01:02:41.265828Z","steps":["trace[2110107228] 'process raft request' (duration: 160.534222ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:02:43.358411Z","caller":"traceutil/trace.go:171","msg":"trace[695432957] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"187.522561ms","start":"2026-06-18T01:02:43.17086Z","end":"2026-06-18T01:02:43.358383Z","steps":["trace[695432957] 'process raft request' (duration: 187.354126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:57.905178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.546502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T01:02:57.905415Z","caller":"traceutil/trace.go:171","msg":"trace[1191617101] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"453.967282ms","start":"2026-06-18T01:02:57.451416Z","end":"2026-06-18T01:02:57.905383Z","steps":["trace[1191617101] 'process raft request' (duration: 86.121802ms)","trace[1191617101] 'compare' (duration: 367.423459ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:02:57.90558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:57.451408Z","time spent":"454.115555ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:57.906061Z","caller":"traceutil/trace.go:171","msg":"trace[885875860] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"452.120203ms","start":"2026-06-18T01:02:57.453879Z","end":"2026-06-18T01:02:57.905999Z","steps":["trace[885875860] 'process raft request' (duration: 451.496797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:57.906324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:57.453873Z","time spent":"452.37625ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:57.906425Z","caller":"traceutil/trace.go:171","msg":"trace[899557166] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"453.771047ms","start":"2026-06-18T01:02:57.452627Z","end":"2026-06-18T01:02:57.906398Z","steps":["trace[899557166] 'process raft request' (duration: 452.673078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:02:57.906495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:57.45262Z","time spent":"453.839369ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:02:57.906542Z","caller":"traceutil/trace.go:171","msg":"trace[110167354] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"262.761693ms","start":"2026-06-18T01:02:57.643768Z","end":"2026-06-18T01:02:57.906529Z","steps":["trace[110167354] 'process raft request' (duration: 261.658453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:00.26675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306379728039214525,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T01:03:01.698085Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.095549699s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-18T01:03:01.703197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100597173s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T01:03:01.704517Z","caller":"traceutil/trace.go:171","msg":"trace[112545616] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"1.967528506s","start":"2026-06-18T01:02:59.736965Z","end":"2026-06-18T01:03:01.704494Z","steps":["trace[112545616] 'process raft request' (duration: 865.543846ms)","trace[112545616] 'compare' (duration: 1.09971s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:03:01.704629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:59.736949Z","time spent":"1.967626179s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:03:01.704706Z","caller":"traceutil/trace.go:171","msg":"trace[1125072551] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"1.793695999s","start":"2026-06-18T01:02:59.910966Z","end":"2026-06-18T01:03:01.704661Z","steps":["trace[1125072551] 'process raft request' (duration: 1.792346813s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:01.705031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939173982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:03:01.70507Z","caller":"traceutil/trace.go:171","msg":"trace[145130199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"1.939236584s","start":"2026-06-18T01:02:59.765822Z","end":"2026-06-18T01:03:01.705059Z","steps":["trace[145130199] 'agreement among raft nodes before linearized reading' (duration: 1.939175112s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:01.705094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:59.765806Z","time spent":"1.939282374s","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-18T01:03:01.705335Z","caller":"traceutil/trace.go:171","msg":"trace[1610830843] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"1.937526447s","start":"2026-06-18T01:02:59.765861Z","end":"2026-06-18T01:03:01.703388Z","steps":["trace[1610830843] 'read index received' (duration: 836.609047ms)","trace[1610830843] 'applied index is now lower than readState.Index' (duration: 1.10091574s)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:03:01.705342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:59.910951Z","time spent":"1.793864753s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:03:01.705464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.876829243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:03:01.705497Z","caller":"traceutil/trace.go:171","msg":"trace[834749240] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1246; }","duration":"1.876892554s","start":"2026-06-18T01:02:59.828595Z","end":"2026-06-18T01:03:01.705488Z","steps":["trace[834749240] 'agreement among raft nodes before linearized reading' (duration: 1.876833663s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:01.705517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:59.828579Z","time spent":"1.876932845s","remote":"127.0.0.1:53890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-18T01:03:01.705731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.924589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-18T01:03:01.705759Z","caller":"traceutil/trace.go:171","msg":"trace[1936057591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"250.97023ms","start":"2026-06-18T01:03:01.454783Z","end":"2026-06-18T01:03:01.705753Z","steps":["trace[1936057591] 'agreement among raft nodes before linearized reading' (duration: 250.858576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:01.705765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794809319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-18T01:03:01.705812Z","caller":"traceutil/trace.go:171","msg":"trace[1553714625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1246; }","duration":"1.794879981s","start":"2026-06-18T01:02:59.910924Z","end":"2026-06-18T01:03:01.705804Z","steps":["trace[1553714625] 'agreement among raft nodes before linearized reading' (duration: 1.794758257s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:01.705833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:59.910905Z","time spent":"1.794922913s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T01:03:01.705912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.796956097s","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-06-18T01:03:01.705937Z","caller":"traceutil/trace.go:171","msg":"trace[1512811556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1246; }","duration":"1.796981938s","start":"2026-06-18T01:02:59.90895Z","end":"2026-06-18T01:03:01.705932Z","steps":["trace[1512811556] 'agreement among raft nodes before linearized reading' (duration: 1.796916586s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:01.705958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:59.908899Z","time spent":"1.797054689s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-18T01:03:01.705989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795003775s","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":"warn","ts":"2026-06-18T01:03:01.706002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.802382ms","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-06-18T01:03:01.707628Z","caller":"traceutil/trace.go:171","msg":"trace[929869965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1246; }","duration":"253.455406ms","start":"2026-06-18T01:03:01.454149Z","end":"2026-06-18T01:03:01.707605Z","steps":["trace[929869965] 'agreement among raft nodes before linearized reading' (duration: 251.676979ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:03:01.70821Z","caller":"traceutil/trace.go:171","msg":"trace[112084794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"1.795070906s","start":"2026-06-18T01:02:59.910934Z","end":"2026-06-18T01:03:01.706004Z","steps":["trace[112084794] 'agreement among raft nodes before linearized reading' (duration: 1.795015025s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:01.710603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:02:59.910915Z","time spent":"1.799638997s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T01:03:16.228067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.543534ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:03:16.228192Z","caller":"traceutil/trace.go:171","msg":"trace[1935833651] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1292; }","duration":"331.678018ms","start":"2026-06-18T01:03:15.896492Z","end":"2026-06-18T01:03:16.22817Z","steps":["trace[1935833651] 'range keys from in-memory index tree' (duration: 331.529784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:16.228462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.405172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-18T01:03:16.228888Z","caller":"traceutil/trace.go:171","msg":"trace[683012825] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"342.978428ms","start":"2026-06-18T01:03:15.885855Z","end":"2026-06-18T01:03:16.228833Z","steps":["trace[683012825] 'process raft request' (duration: 239.114024ms)","trace[683012825] 'compare' (duration: 102.803537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:03:16.22914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:15.88584Z","time spent":"343.136463ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:03:16.578614Z","caller":"traceutil/trace.go:171","msg":"trace[1223830231] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"691.675949ms","start":"2026-06-18T01:03:15.886921Z","end":"2026-06-18T01:03:16.578597Z","steps":["trace[1223830231] 'process raft request' (duration: 691.609927ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:03:16.578642Z","caller":"traceutil/trace.go:171","msg":"trace[349477981] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"691.137236ms","start":"2026-06-18T01:03:15.887488Z","end":"2026-06-18T01:03:16.578626Z","steps":["trace[349477981] 'process raft request' (duration: 691.066784ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:03:16.578627Z","caller":"traceutil/trace.go:171","msg":"trace[788659319] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"692.402099ms","start":"2026-06-18T01:03:15.886199Z","end":"2026-06-18T01:03:16.578601Z","steps":["trace[788659319] 'process raft request' (duration: 683.882211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:16.578723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:15.886913Z","time spent":"691.755072ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:03:16.57876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:15.887481Z","time spent":"691.223257ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:03:16.578764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:15.886185Z","time spent":"692.510731ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:03:16.578879Z","caller":"traceutil/trace.go:171","msg":"trace[951888970] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"692.649785ms","start":"2026-06-18T01:03:15.886221Z","end":"2026-06-18T01:03:16.578871Z","steps":["trace[951888970] 'process raft request' (duration: 692.266554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:16.578953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:15.886213Z","time spent":"692.719046ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:03:18.354447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:17.872828Z","time spent":"481.614828ms","remote":"127.0.0.1:51966","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-18T01:03:18.542344Z","caller":"traceutil/trace.go:171","msg":"trace[1296346042] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"309.17997ms","start":"2026-06-18T01:03:18.233144Z","end":"2026-06-18T01:03:18.542324Z","steps":["trace[1296346042] 'read index received' (duration: 121.189746ms)","trace[1296346042] 'applied index is now lower than readState.Index' (duration: 187.989594ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:03:18.54252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.371174ms","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-06-18T01:03:18.542549Z","caller":"traceutil/trace.go:171","msg":"trace[2145153383] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"309.455776ms","start":"2026-06-18T01:03:18.233087Z","end":"2026-06-18T01:03:18.542543Z","steps":["trace[2145153383] 'agreement among raft nodes before linearized reading' (duration: 309.312523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:18.542572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:18.233065Z","time spent":"309.502108ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-18T01:03:18.542615Z","caller":"traceutil/trace.go:171","msg":"trace[4437744] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"657.652323ms","start":"2026-06-18T01:03:17.884934Z","end":"2026-06-18T01:03:18.542586Z","steps":["trace[4437744] 'process raft request' (duration: 637.576208ms)","trace[4437744] 'compare' (duration: 19.729536ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:03:18.542762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:17.884917Z","time spent":"657.768286ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:03:18.559332Z","caller":"traceutil/trace.go:171","msg":"trace[934577495] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"203.760903ms","start":"2026-06-18T01:03:18.355541Z","end":"2026-06-18T01:03:18.559302Z","steps":["trace[934577495] 'process raft request' (duration: 203.64551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:19.569771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.358733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-06-18T01:03:19.569848Z","caller":"traceutil/trace.go:171","msg":"trace[954759830] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1306; }","duration":"661.492406ms","start":"2026-06-18T01:03:18.908341Z","end":"2026-06-18T01:03:19.569834Z","steps":["trace[954759830] 'range keys from in-memory index tree' (duration: 661.189917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:19.569881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:18.908322Z","time spent":"661.551787ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5355,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-18T01:03:19.570163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.780013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-18T01:03:19.570284Z","caller":"traceutil/trace.go:171","msg":"trace[1627738292] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"383.549288ms","start":"2026-06-18T01:03:19.186723Z","end":"2026-06-18T01:03:19.570273Z","steps":["trace[1627738292] 'process raft request' (duration: 31.506588ms)","trace[1627738292] 'compare' (duration: 351.540566ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T01:03:19.570291Z","caller":"traceutil/trace.go:171","msg":"trace[225808095] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"313.525395ms","start":"2026-06-18T01:03:19.256743Z","end":"2026-06-18T01:03:19.570269Z","steps":["trace[225808095] 'read index received' (duration: 208.846µs)","trace[225808095] 'applied index is now lower than readState.Index' (duration: 313.314209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-18T01:03:19.570347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:19.186706Z","time spent":"383.6019ms","remote":"127.0.0.1:52000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-18T01:03:19.570425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.666538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:03:19.570467Z","caller":"traceutil/trace.go:171","msg":"trace[2116017159] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1307; }","duration":"313.757751ms","start":"2026-06-18T01:03:19.256694Z","end":"2026-06-18T01:03:19.570451Z","steps":["trace[2116017159] 'agreement among raft nodes before linearized reading' (duration: 313.636567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:19.570511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:19.256669Z","time spent":"313.829642ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-06-18T01:03:24.736211Z","caller":"traceutil/trace.go:171","msg":"trace[1253358151] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"107.419759ms","start":"2026-06-18T01:03:24.628761Z","end":"2026-06-18T01:03:24.736181Z","steps":["trace[1253358151] 'process raft request' (duration: 106.905145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.199736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306379728039214874,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-18T01:03:33.250393Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.233853128s","expected-duration":"1s"} {"level":"info","ts":"2026-06-18T01:03:33.250908Z","caller":"traceutil/trace.go:171","msg":"trace[735429418] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"1.234432534s","start":"2026-06-18T01:03:32.016449Z","end":"2026-06-18T01:03:33.250881Z","steps":["trace[735429418] 'process raft request' (duration: 1.234212068s)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.251106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.016428Z","time spent":"1.234567998s","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-18T01:03:33.273631Z","caller":"traceutil/trace.go:171","msg":"trace[642358429] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1461; }","duration":"574.114859ms","start":"2026-06-18T01:03:32.699494Z","end":"2026-06-18T01:03:33.273609Z","steps":["trace[642358429] 'read index received' (duration: 551.18549ms)","trace[642358429] 'applied index is now lower than readState.Index' (duration: 22.928089ms)"],"step_count":2} {"level":"info","ts":"2026-06-18T01:03:33.273736Z","caller":"traceutil/trace.go:171","msg":"trace[837324273] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"688.091533ms","start":"2026-06-18T01:03:32.585629Z","end":"2026-06-18T01:03:33.273721Z","steps":["trace[837324273] 'process raft request' (duration: 687.933818ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:03:33.273751Z","caller":"traceutil/trace.go:171","msg":"trace[2135656948] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"795.955365ms","start":"2026-06-18T01:03:32.477743Z","end":"2026-06-18T01:03:33.273698Z","steps":["trace[2135656948] 'process raft request' (duration: 795.648696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.273835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.585594Z","time spent":"688.167065ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:03:33.273985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.477714Z","time spent":"796.147189ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-18T01:03:33.274216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.722646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-18T01:03:33.274373Z","caller":"traceutil/trace.go:171","msg":"trace[1830338074] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1409; }","duration":"574.914881ms","start":"2026-06-18T01:03:32.699436Z","end":"2026-06-18T01:03:33.274351Z","steps":["trace[1830338074] 'agreement among raft nodes before linearized reading' (duration: 574.736437ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.274421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.699414Z","time spent":"574.994194ms","remote":"127.0.0.1:39436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-18T01:03:33.27444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.04251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-18T01:03:33.27452Z","caller":"traceutil/trace.go:171","msg":"trace[1865511796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1409; }","duration":"491.160513ms","start":"2026-06-18T01:03:32.783339Z","end":"2026-06-18T01:03:33.274499Z","steps":["trace[1865511796] 'agreement among raft nodes before linearized reading' (duration: 490.935456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.274559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.783328Z","time spent":"491.224924ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-18T01:03:33.274614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.125658ms","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-06-18T01:03:33.274706Z","caller":"traceutil/trace.go:171","msg":"trace[1617134513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1409; }","duration":"498.265932ms","start":"2026-06-18T01:03:32.776418Z","end":"2026-06-18T01:03:33.274684Z","steps":["trace[1617134513] 'agreement among raft nodes before linearized reading' (duration: 497.987135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.274747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.521621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-06-18T01:03:33.274764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.776391Z","time spent":"498.358424ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-18T01:03:33.274788Z","caller":"traceutil/trace.go:171","msg":"trace[1340551687] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1409; }","duration":"429.616465ms","start":"2026-06-18T01:03:32.845163Z","end":"2026-06-18T01:03:33.274779Z","steps":["trace[1340551687] 'agreement among raft nodes before linearized reading' (duration: 429.504142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.274815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.919526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-18T01:03:33.274824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.845132Z","time spent":"429.679206ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-18T01:03:33.274858Z","caller":"traceutil/trace.go:171","msg":"trace[1168051121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1409; }","duration":"490.990478ms","start":"2026-06-18T01:03:32.783858Z","end":"2026-06-18T01:03:33.274848Z","steps":["trace[1168051121] 'agreement among raft nodes before linearized reading' (duration: 490.857454ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.274592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.466402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-18T01:03:33.274887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.783844Z","time spent":"491.036418ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-18T01:03:33.27457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.228238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-18T01:03:33.274914Z","caller":"traceutil/trace.go:171","msg":"trace[41653310] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1409; }","duration":"529.911614ms","start":"2026-06-18T01:03:32.744979Z","end":"2026-06-18T01:03:33.274891Z","steps":["trace[41653310] 'agreement among raft nodes before linearized reading' (duration: 529.254656ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:03:33.274936Z","caller":"traceutil/trace.go:171","msg":"trace[1972478234] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1409; }","duration":"304.662798ms","start":"2026-06-18T01:03:32.970257Z","end":"2026-06-18T01:03:33.274919Z","steps":["trace[1972478234] 'agreement among raft nodes before linearized reading' (duration: 304.087503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.274973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.970233Z","time spent":"304.72596ms","remote":"127.0.0.1:53904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-18T01:03:33.274982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.929838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T01:03:33.274976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.744949Z","time spent":"530.009276ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-06-18T01:03:33.275012Z","caller":"traceutil/trace.go:171","msg":"trace[885120064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1409; }","duration":"507.97696ms","start":"2026-06-18T01:03:32.767027Z","end":"2026-06-18T01:03:33.275004Z","steps":["trace[885120064] 'agreement among raft nodes before linearized reading' (duration: 507.934358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.275037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.767015Z","time spent":"508.0172ms","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-18T01:03:33.275088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.919538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-18T01:03:33.275207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.089539ms","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-06-18T01:03:33.275351Z","caller":"traceutil/trace.go:171","msg":"trace[897409517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1409; }","duration":"506.223653ms","start":"2026-06-18T01:03:32.769076Z","end":"2026-06-18T01:03:33.275299Z","steps":["trace[897409517] 'agreement among raft nodes before linearized reading' (duration: 505.595686ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:03:33.275356Z","caller":"traceutil/trace.go:171","msg":"trace[81851172] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1409; }","duration":"183.196466ms","start":"2026-06-18T01:03:33.091995Z","end":"2026-06-18T01:03:33.275191Z","steps":["trace[81851172] 'agreement among raft nodes before linearized reading' (duration: 182.711333ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.275512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.769069Z","time spent":"506.344866ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-18T01:03:33.276144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.117671ms","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-06-18T01:03:33.276271Z","caller":"traceutil/trace.go:171","msg":"trace[183716794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1409; }","duration":"500.273704ms","start":"2026-06-18T01:03:32.775959Z","end":"2026-06-18T01:03:33.276233Z","steps":["trace[183716794] 'agreement among raft nodes before linearized reading' (duration: 498.319992ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:03:33.276337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-18T01:03:32.775935Z","time spent":"500.391267ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-18T01:07:22.510549Z","caller":"traceutil/trace.go:171","msg":"trace[1632860338] linearizableReadLoop","detail":"{readStateIndex:2584; appliedIndex:2583; }","duration":"125.182149ms","start":"2026-06-18T01:07:22.385343Z","end":"2026-06-18T01:07:22.510525Z","steps":["trace[1632860338] 'read index received' (duration: 124.993004ms)","trace[1632860338] 'applied index is now lower than readState.Index' (duration: 188.265µs)"],"step_count":2} {"level":"info","ts":"2026-06-18T01:07:22.510693Z","caller":"traceutil/trace.go:171","msg":"trace[1896650043] transaction","detail":"{read_only:false; response_revision:2482; number_of_response:1; }","duration":"127.82645ms","start":"2026-06-18T01:07:22.382844Z","end":"2026-06-18T01:07:22.510671Z","steps":["trace[1896650043] 'process raft request' (duration: 127.541382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:07:22.510833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.465067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-18T01:07:22.510916Z","caller":"traceutil/trace.go:171","msg":"trace[2066073878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2482; }","duration":"125.547199ms","start":"2026-06-18T01:07:22.385326Z","end":"2026-06-18T01:07:22.510873Z","steps":["trace[2066073878] 'agreement among raft nodes before linearized reading' (duration: 125.346174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:07:22.512354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.543309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-18T01:07:22.512428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.977866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-18T01:07:22.512443Z","caller":"traceutil/trace.go:171","msg":"trace[332055202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2483; }","duration":"125.653241ms","start":"2026-06-18T01:07:22.386774Z","end":"2026-06-18T01:07:22.512427Z","steps":["trace[332055202] 'agreement among raft nodes before linearized reading' (duration: 125.426796ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:07:22.512475Z","caller":"traceutil/trace.go:171","msg":"trace[1320863870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2483; }","duration":"122.034527ms","start":"2026-06-18T01:07:22.390427Z","end":"2026-06-18T01:07:22.512462Z","steps":["trace[1320863870] 'agreement among raft nodes before linearized reading' (duration: 121.916334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-18T01:07:22.512516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.967158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-18T01:07:22.512544Z","caller":"traceutil/trace.go:171","msg":"trace[1125808871] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"126.394281ms","start":"2026-06-18T01:07:22.38614Z","end":"2026-06-18T01:07:22.512534Z","steps":["trace[1125808871] 'process raft request' (duration: 125.96008ms)"],"step_count":1} {"level":"info","ts":"2026-06-18T01:07:22.512583Z","caller":"traceutil/trace.go:171","msg":"trace[47103699] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2483; }","duration":"124.06077ms","start":"2026-06-18T01:07:22.388507Z","end":"2026-06-18T01:07:22.512568Z","steps":["trace[47103699] 'agreement among raft nodes before linearized reading' (duration: 123.856005ms)"],"step_count":1}