{"level":"warn","ts":"2026-04-23T16:30:52.799306Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T16:30:52.7994Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.186: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.186:2380","--initial-cluster=instance=https://199.19.213.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.186: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-04-23T16:30:52.799469Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T16:30:52.799479Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-04-23T16:30:52.799495Z","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-04-23T16:30:52.800121Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"]} {"level":"info","ts":"2026-04-23T16:30:52.800192Z","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.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.186: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-04-23T16:30:52.80628Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.880653ms"} {"level":"info","ts":"2026-04-23T16:30:52.81497Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"22478416d949d47d","cluster-id":"568bb29601528c07"} {"level":"info","ts":"2026-04-23T16:30:52.815045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T16:30:52.815074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 0"} {"level":"info","ts":"2026-04-23T16:30:52.815092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 22478416d949d47d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T16:30:52.815101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became follower at term 1"} {"level":"info","ts":"2026-04-23T16:30:52.815143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"warn","ts":"2026-04-23T16:30:52.82767Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T16:30:52.831187Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T16:30:52.833135Z","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-04-23T16:30:52.837103Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"22478416d949d47d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T16:30:52.842008Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"22478416d949d47d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T16:30:52.842297Z","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-04-23T16:30:52.842357Z","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-04-23T16:30:52.842375Z","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-04-23T16:30:52.842804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d switched to configuration voters=(2470088154305647741)"} {"level":"info","ts":"2026-04-23T16:30:52.842937Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","added-peer-id":"22478416d949d47d","added-peer-peer-urls":["https://199.19.213.186:2380"]} {"level":"info","ts":"2026-04-23T16:30:52.847365Z","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-04-23T16:30:52.847465Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-04-23T16:30:52.847488Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.186:2380"} {"level":"info","ts":"2026-04-23T16:30:52.847626Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"22478416d949d47d","initial-advertise-peer-urls":["https://199.19.213.186:2380"],"listen-peer-urls":["https://199.19.213.186:2380"],"advertise-client-urls":["https://199.19.213.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T16:30:52.847651Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T16:30:53.616008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T16:30:53.616154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T16:30:53.616256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgPreVoteResp from 22478416d949d47d at term 1"} {"level":"info","ts":"2026-04-23T16:30:53.616332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became candidate at term 2"} {"level":"info","ts":"2026-04-23T16:30:53.616379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d received MsgVoteResp from 22478416d949d47d at term 2"} {"level":"info","ts":"2026-04-23T16:30:53.616434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"22478416d949d47d became leader at term 2"} {"level":"info","ts":"2026-04-23T16:30:53.616503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 22478416d949d47d elected leader 22478416d949d47d at term 2"} {"level":"info","ts":"2026-04-23T16:30:53.620711Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:30:53.620717Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"22478416d949d47d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.186:2379]}","request-path":"/0/members/22478416d949d47d/attributes","cluster-id":"568bb29601528c07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T16:30:53.621187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T16:30:53.621808Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T16:30:53.621842Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T16:30:53.624157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.186:2379"} {"level":"info","ts":"2026-04-23T16:30:53.624543Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T16:30:53.634771Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:53.650588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"568bb29601528c07","local-member-id":"22478416d949d47d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:53.650785Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:53.650828Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T16:30:56.516231Z","caller":"traceutil/trace.go:171","msg":"trace[303548366] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"130.611742ms","start":"2026-04-23T16:30:56.38559Z","end":"2026-04-23T16:30:56.516202Z","steps":["trace[303548366] 'process raft request' (duration: 123.898477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:57.858064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.36972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:30:57.858148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.675713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-04-23T16:30:57.858191Z","caller":"traceutil/trace.go:171","msg":"trace[498368230] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:256; }","duration":"483.57297ms","start":"2026-04-23T16:30:57.3746Z","end":"2026-04-23T16:30:57.858173Z","steps":["trace[498368230] 'range keys from in-memory index tree' (duration: 483.28309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:57.858299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:57.374576Z","time spent":"483.7102ms","remote":"127.0.0.1:42156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-23T16:30:57.858223Z","caller":"traceutil/trace.go:171","msg":"trace[1935445002] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:256; }","duration":"350.803973ms","start":"2026-04-23T16:30:57.507402Z","end":"2026-04-23T16:30:57.858206Z","steps":["trace[1935445002] 'range keys from in-memory index tree' (duration: 350.567603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:57.858514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:30:57.507381Z","time spent":"351.116214ms","remote":"127.0.0.1:42130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-23T16:30:59.259377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.338155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:30:59.259539Z","caller":"traceutil/trace.go:171","msg":"trace[1578319867] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"243.458324ms","start":"2026-04-23T16:30:59.016058Z","end":"2026-04-23T16:30:59.259516Z","steps":["trace[1578319867] 'compare' (duration: 228.18603ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.278077Z","caller":"traceutil/trace.go:171","msg":"trace[610847947] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:304; }","duration":"254.786839ms","start":"2026-04-23T16:30:59.023235Z","end":"2026-04-23T16:30:59.278022Z","steps":["trace[610847947] 'read index received' (duration: 3.609498ms)","trace[610847947] 'applied index is now lower than readState.Index' (duration: 251.176361ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:59.278186Z","caller":"traceutil/trace.go:171","msg":"trace[1912918178] transaction","detail":"{read_only:false; number_of_response:0; response_revision:299; }","duration":"262.047524ms","start":"2026-04-23T16:30:59.016127Z","end":"2026-04-23T16:30:59.278175Z","steps":["trace[1912918178] 'process raft request' (duration: 261.811554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:59.278468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.208479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:30:59.278599Z","caller":"traceutil/trace.go:171","msg":"trace[1223910205] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:299; }","duration":"255.34693ms","start":"2026-04-23T16:30:59.02323Z","end":"2026-04-23T16:30:59.278577Z","steps":["trace[1223910205] 'agreement among raft nodes before linearized reading' (duration: 255.120119ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.317487Z","caller":"traceutil/trace.go:171","msg":"trace[340394924] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"285.193557ms","start":"2026-04-23T16:30:59.03226Z","end":"2026-04-23T16:30:59.317453Z","steps":["trace[340394924] 'process raft request' (duration: 284.938016ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.317699Z","caller":"traceutil/trace.go:171","msg":"trace[1847831793] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"282.395331ms","start":"2026-04-23T16:30:59.035276Z","end":"2026-04-23T16:30:59.317671Z","steps":["trace[1847831793] 'process raft request' (duration: 282.200931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:59.317908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.619036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:30:59.317967Z","caller":"traceutil/trace.go:171","msg":"trace[1216259618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:304; }","duration":"145.717216ms","start":"2026-04-23T16:30:59.172233Z","end":"2026-04-23T16:30:59.31795Z","steps":["trace[1216259618] 'agreement among raft nodes before linearized reading' (duration: 145.585716ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.318066Z","caller":"traceutil/trace.go:171","msg":"trace[893960472] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"284.447695ms","start":"2026-04-23T16:30:59.033593Z","end":"2026-04-23T16:30:59.318041Z","steps":["trace[893960472] 'process raft request' (duration: 283.822164ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.318206Z","caller":"traceutil/trace.go:171","msg":"trace[215563215] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"283.788493ms","start":"2026-04-23T16:30:59.034407Z","end":"2026-04-23T16:30:59.318196Z","steps":["trace[215563215] 'process raft request' (duration: 283.044702ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.318102Z","caller":"traceutil/trace.go:171","msg":"trace[2038333175] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"251.27045ms","start":"2026-04-23T16:30:59.066781Z","end":"2026-04-23T16:30:59.318052Z","steps":["trace[2038333175] 'process raft request' (duration: 250.743009ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.318303Z","caller":"traceutil/trace.go:171","msg":"trace[9158602] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"285.670548ms","start":"2026-04-23T16:30:59.032549Z","end":"2026-04-23T16:30:59.31822Z","steps":["trace[9158602] 'process raft request' (duration: 284.832427ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.479653Z","caller":"traceutil/trace.go:171","msg":"trace[1272994458] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"157.176741ms","start":"2026-04-23T16:30:59.322434Z","end":"2026-04-23T16:30:59.47961Z","steps":["trace[1272994458] 'process raft request' (duration: 64.121804ms)","trace[1272994458] 'compare' (duration: 92.742576ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:59.514265Z","caller":"traceutil/trace.go:171","msg":"trace[501204569] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"191.731468ms","start":"2026-04-23T16:30:59.322478Z","end":"2026-04-23T16:30:59.51421Z","steps":["trace[501204569] 'process raft request' (duration: 191.528098ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.514286Z","caller":"traceutil/trace.go:171","msg":"trace[317677381] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:313; }","duration":"190.262554ms","start":"2026-04-23T16:30:59.323997Z","end":"2026-04-23T16:30:59.51426Z","steps":["trace[317677381] 'read index received' (duration: 62.57138ms)","trace[317677381] 'applied index is now lower than readState.Index' (duration: 127.689864ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:30:59.514362Z","caller":"traceutil/trace.go:171","msg":"trace[559946658] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"191.777467ms","start":"2026-04-23T16:30:59.322546Z","end":"2026-04-23T16:30:59.514323Z","steps":["trace[559946658] 'process raft request' (duration: 191.602397ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.514418Z","caller":"traceutil/trace.go:171","msg":"trace[1617171732] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"191.748647ms","start":"2026-04-23T16:30:59.322653Z","end":"2026-04-23T16:30:59.514402Z","steps":["trace[1617171732] 'process raft request' (duration: 191.546307ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:30:59.514632Z","caller":"traceutil/trace.go:171","msg":"trace[491485889] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"191.890648ms","start":"2026-04-23T16:30:59.322517Z","end":"2026-04-23T16:30:59.514408Z","steps":["trace[491485889] 'process raft request' (duration: 191.593078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:59.514989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.765956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-23T16:30:59.515055Z","caller":"traceutil/trace.go:171","msg":"trace[19471804] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:309; }","duration":"191.094897ms","start":"2026-04-23T16:30:59.323945Z","end":"2026-04-23T16:30:59.51504Z","steps":["trace[19471804] 'agreement among raft nodes before linearized reading' (duration: 190.735196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:30:59.569549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.117226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:30:59.56965Z","caller":"traceutil/trace.go:171","msg":"trace[1096747072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:310; }","duration":"231.237856ms","start":"2026-04-23T16:30:59.338373Z","end":"2026-04-23T16:30:59.569611Z","steps":["trace[1096747072] 'agreement among raft nodes before linearized reading' (duration: 231.066065ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.092668Z","caller":"traceutil/trace.go:171","msg":"trace[455332243] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"118.467594ms","start":"2026-04-23T16:31:01.974163Z","end":"2026-04-23T16:31:02.092631Z","steps":["trace[455332243] 'process raft request' (duration: 110.620707ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.092675Z","caller":"traceutil/trace.go:171","msg":"trace[1025634472] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"118.208283ms","start":"2026-04-23T16:31:01.97444Z","end":"2026-04-23T16:31:02.092648Z","steps":["trace[1025634472] 'process raft request' (duration: 118.102933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:02.393695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.392447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T16:31:02.393923Z","caller":"traceutil/trace.go:171","msg":"trace[941723726] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:360; }","duration":"220.211181ms","start":"2026-04-23T16:31:02.173692Z","end":"2026-04-23T16:31:02.393904Z","steps":["trace[941723726] 'read index received' (duration: 19.238513ms)","trace[941723726] 'applied index is now lower than readState.Index' (duration: 200.971268ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:02.393969Z","caller":"traceutil/trace.go:171","msg":"trace[131456554] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"253.171944ms","start":"2026-04-23T16:31:02.14075Z","end":"2026-04-23T16:31:02.393922Z","steps":["trace[131456554] 'process raft request' (duration: 52.227336ms)","trace[131456554] 'compare' (duration: 200.252587ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:02.394019Z","caller":"traceutil/trace.go:171","msg":"trace[1484327044] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"220.951392ms","start":"2026-04-23T16:31:02.173025Z","end":"2026-04-23T16:31:02.393976Z","steps":["trace[1484327044] 'process raft request' (duration: 220.802272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:02.394111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.378269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-23T16:31:02.394152Z","caller":"traceutil/trace.go:171","msg":"trace[665365042] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:351; }","duration":"170.461119ms","start":"2026-04-23T16:31:02.22368Z","end":"2026-04-23T16:31:02.394141Z","steps":["trace[665365042] 'agreement among raft nodes before linearized reading' (duration: 170.347619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:02.394273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.501306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-23T16:31:02.394313Z","caller":"traceutil/trace.go:171","msg":"trace[810159833] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:351; }","duration":"119.606267ms","start":"2026-04-23T16:31:02.274696Z","end":"2026-04-23T16:31:02.394302Z","steps":["trace[810159833] 'agreement among raft nodes before linearized reading' (duration: 119.507836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:02.394449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.677313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-23T16:31:02.394524Z","caller":"traceutil/trace.go:171","msg":"trace[2126083622] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:351; }","duration":"220.844943ms","start":"2026-04-23T16:31:02.173661Z","end":"2026-04-23T16:31:02.394506Z","steps":["trace[2126083622] 'agreement among raft nodes before linearized reading' (duration: 220.343511ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.703412Z","caller":"traceutil/trace.go:171","msg":"trace[1673014044] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"228.729341ms","start":"2026-04-23T16:31:02.47463Z","end":"2026-04-23T16:31:02.703359Z","steps":["trace[1673014044] 'process raft request' (duration: 132.437445ms)","trace[1673014044] 'compare' (duration: 96.011915ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:02.736748Z","caller":"traceutil/trace.go:171","msg":"trace[2021033255] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"256.394702ms","start":"2026-04-23T16:31:02.48033Z","end":"2026-04-23T16:31:02.736725Z","steps":["trace[2021033255] 'process raft request' (duration: 256.352122ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.737223Z","caller":"traceutil/trace.go:171","msg":"trace[1745689114] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"258.932387ms","start":"2026-04-23T16:31:02.478265Z","end":"2026-04-23T16:31:02.737197Z","steps":["trace[1745689114] 'process raft request' (duration: 258.206126ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.737321Z","caller":"traceutil/trace.go:171","msg":"trace[1243147121] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"258.302696ms","start":"2026-04-23T16:31:02.478925Z","end":"2026-04-23T16:31:02.737227Z","steps":["trace[1243147121] 'process raft request' (duration: 257.712635ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.737864Z","caller":"traceutil/trace.go:171","msg":"trace[686383568] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"258.309016ms","start":"2026-04-23T16:31:02.478823Z","end":"2026-04-23T16:31:02.737132Z","steps":["trace[686383568] 'process raft request' (duration: 257.771845ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.941089Z","caller":"traceutil/trace.go:171","msg":"trace[1768918249] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"165.350499ms","start":"2026-04-23T16:31:02.775723Z","end":"2026-04-23T16:31:02.941074Z","steps":["trace[1768918249] 'process raft request' (duration: 165.301069ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.941289Z","caller":"traceutil/trace.go:171","msg":"trace[1065130062] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:379; }","duration":"172.598265ms","start":"2026-04-23T16:31:02.768668Z","end":"2026-04-23T16:31:02.941267Z","steps":["trace[1065130062] 'read index received' (duration: 115.268837ms)","trace[1065130062] 'applied index is now lower than readState.Index' (duration: 57.327248ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:02.941074Z","caller":"traceutil/trace.go:171","msg":"trace[735322494] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"198.203732ms","start":"2026-04-23T16:31:02.742844Z","end":"2026-04-23T16:31:02.941048Z","steps":["trace[735322494] 'process raft request' (duration: 198.075482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:02.941508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.831102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-04-23T16:31:02.941504Z","caller":"traceutil/trace.go:171","msg":"trace[555392432] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"193.441772ms","start":"2026-04-23T16:31:02.747984Z","end":"2026-04-23T16:31:02.941426Z","steps":["trace[555392432] 'process raft request' (duration: 192.994671ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.941593Z","caller":"traceutil/trace.go:171","msg":"trace[2040094843] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:372; }","duration":"193.944052ms","start":"2026-04-23T16:31:02.747633Z","end":"2026-04-23T16:31:02.941577Z","steps":["trace[2040094843] 'agreement among raft nodes before linearized reading' (duration: 193.803902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:02.941602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.137185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-vdknd\" ","response":"range_response_count:1 size:3439"} {"level":"warn","ts":"2026-04-23T16:31:02.941586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.666418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-23T16:31:02.941978Z","caller":"traceutil/trace.go:171","msg":"trace[857706225] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:372; }","duration":"165.074049ms","start":"2026-04-23T16:31:02.776891Z","end":"2026-04-23T16:31:02.941965Z","steps":["trace[857706225] 'agreement among raft nodes before linearized reading' (duration: 164.630798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:02.942003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.069163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-23T16:31:02.942108Z","caller":"traceutil/trace.go:171","msg":"trace[1253682634] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:372; }","duration":"167.190143ms","start":"2026-04-23T16:31:02.774908Z","end":"2026-04-23T16:31:02.942098Z","steps":["trace[1253682634] 'agreement among raft nodes before linearized reading' (duration: 167.012743ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.941894Z","caller":"traceutil/trace.go:171","msg":"trace[1243670063] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-vdknd; range_end:; response_count:1; response_revision:372; }","duration":"168.467166ms","start":"2026-04-23T16:31:02.773412Z","end":"2026-04-23T16:31:02.94188Z","steps":["trace[1243670063] 'agreement among raft nodes before linearized reading' (duration: 168.095365ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:02.942487Z","caller":"traceutil/trace.go:171","msg":"trace[1919432281] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"199.548704ms","start":"2026-04-23T16:31:02.741585Z","end":"2026-04-23T16:31:02.941134Z","steps":["trace[1919432281] 'process raft request' (duration: 142.327746ms)","trace[1919432281] 'compare' (duration: 56.857917ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:03.06547Z","caller":"traceutil/trace.go:171","msg":"trace[1733196415] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"107.541779ms","start":"2026-04-23T16:31:02.957902Z","end":"2026-04-23T16:31:03.065444Z","steps":["trace[1733196415] 'process raft request' (duration: 90.27943ms)","trace[1733196415] 'compare' (duration: 17.120179ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:03.065851Z","caller":"traceutil/trace.go:171","msg":"trace[1352812302] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"107.83437ms","start":"2026-04-23T16:31:02.958006Z","end":"2026-04-23T16:31:03.06584Z","steps":["trace[1352812302] 'process raft request' (duration: 107.588559ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:03.065889Z","caller":"traceutil/trace.go:171","msg":"trace[571105559] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"104.548453ms","start":"2026-04-23T16:31:02.961315Z","end":"2026-04-23T16:31:03.065863Z","steps":["trace[571105559] 'process raft request' (duration: 104.445203ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:14.29344Z","caller":"traceutil/trace.go:171","msg":"trace[152060259] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"136.388913ms","start":"2026-04-23T16:31:14.157021Z","end":"2026-04-23T16:31:14.29341Z","steps":["trace[152060259] 'process raft request' (duration: 136.228873ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:14.418493Z","caller":"traceutil/trace.go:171","msg":"trace[237129517] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"259.423528ms","start":"2026-04-23T16:31:14.159051Z","end":"2026-04-23T16:31:14.418474Z","steps":["trace[237129517] 'process raft request' (duration: 257.313163ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:18.704739Z","caller":"traceutil/trace.go:171","msg":"trace[1947859158] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"268.736747ms","start":"2026-04-23T16:31:18.435983Z","end":"2026-04-23T16:31:18.70472Z","steps":["trace[1947859158] 'process raft request' (duration: 268.618366ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:22.056207Z","caller":"traceutil/trace.go:171","msg":"trace[1573335437] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"625.435368ms","start":"2026-04-23T16:31:21.430753Z","end":"2026-04-23T16:31:22.056188Z","steps":["trace[1573335437] 'process raft request' (duration: 619.938646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:22.056352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:21.430729Z","time spent":"625.548088ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:31:22.061952Z","caller":"traceutil/trace.go:171","msg":"trace[1259188618] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"576.644879ms","start":"2026-04-23T16:31:21.485289Z","end":"2026-04-23T16:31:22.061934Z","steps":["trace[1259188618] 'process raft request' (duration: 576.251008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:22.062088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:21.485255Z","time spent":"576.751809ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:31:22.062854Z","caller":"traceutil/trace.go:171","msg":"trace[667106698] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"373.266539ms","start":"2026-04-23T16:31:21.689559Z","end":"2026-04-23T16:31:22.062826Z","steps":["trace[667106698] 'read index received' (duration: 361.128542ms)","trace[667106698] 'applied index is now lower than readState.Index' (duration: 12.136927ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:22.062993Z","caller":"traceutil/trace.go:171","msg":"trace[747173492] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"388.043261ms","start":"2026-04-23T16:31:21.67492Z","end":"2026-04-23T16:31:22.062963Z","steps":["trace[747173492] 'process raft request' (duration: 387.42203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:22.063148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:21.674893Z","time spent":"388.177942ms","remote":"127.0.0.1:41980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:31:22.063389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.80922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T16:31:22.063403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.779597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T16:31:22.063448Z","caller":"traceutil/trace.go:171","msg":"trace[1525262777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"210.878077ms","start":"2026-04-23T16:31:21.852557Z","end":"2026-04-23T16:31:22.063435Z","steps":["trace[1525262777] 'agreement among raft nodes before linearized reading' (duration: 210.787357ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:22.063509Z","caller":"traceutil/trace.go:171","msg":"trace[1287148929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"373.92957ms","start":"2026-04-23T16:31:21.689499Z","end":"2026-04-23T16:31:22.063428Z","steps":["trace[1287148929] 'agreement among raft nodes before linearized reading' (duration: 373.515529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:22.063597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:21.68947Z","time spent":"374.11612ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T16:31:28.591655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.748852ms","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-04-23T16:31:28.591703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.117905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-23T16:31:28.591703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.431289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-23T16:31:28.591774Z","caller":"traceutil/trace.go:171","msg":"trace[1640718820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"208.929502ms","start":"2026-04-23T16:31:28.382821Z","end":"2026-04-23T16:31:28.59175Z","steps":["trace[1640718820] 'range keys from in-memory index tree' (duration: 208.548892ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:28.591798Z","caller":"traceutil/trace.go:171","msg":"trace[736615058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"476.258215ms","start":"2026-04-23T16:31:28.115517Z","end":"2026-04-23T16:31:28.591775Z","steps":["trace[736615058] 'range keys from in-memory index tree' (duration: 475.937065ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:28.591824Z","caller":"traceutil/trace.go:171","msg":"trace[2141334769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"478.629569ms","start":"2026-04-23T16:31:28.113172Z","end":"2026-04-23T16:31:28.591802Z","steps":["trace[2141334769] 'range keys from in-memory index tree' (duration: 478.198809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:28.591703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.304462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sgks5\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-23T16:31:28.5919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:28.113145Z","time spent":"478.73961ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T16:31:28.591919Z","caller":"traceutil/trace.go:171","msg":"trace[1633162992] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sgks5; range_end:; response_count:1; response_revision:621; }","duration":"565.590543ms","start":"2026-04-23T16:31:28.026309Z","end":"2026-04-23T16:31:28.5919Z","steps":["trace[1633162992] 'range keys from in-memory index tree' (duration: 565.199672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:28.591859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:28.115498Z","time spent":"476.349586ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-23T16:31:28.591975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:28.026288Z","time spent":"565.672523ms","remote":"127.0.0.1:42132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-sgks5\" "} {"level":"info","ts":"2026-04-23T16:31:28.757327Z","caller":"traceutil/trace.go:171","msg":"trace[37008245] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"160.818166ms","start":"2026-04-23T16:31:28.596479Z","end":"2026-04-23T16:31:28.757297Z","steps":["trace[37008245] 'process raft request' (duration: 160.650166ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:28.833005Z","caller":"traceutil/trace.go:171","msg":"trace[267943447] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"235.769952ms","start":"2026-04-23T16:31:28.597192Z","end":"2026-04-23T16:31:28.832962Z","steps":["trace[267943447] 'process raft request' (duration: 213.725204ms)","trace[267943447] 'compare' (duration: 21.691797ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:28.833054Z","caller":"traceutil/trace.go:171","msg":"trace[946761451] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"223.166095ms","start":"2026-04-23T16:31:28.609847Z","end":"2026-04-23T16:31:28.833013Z","steps":["trace[946761451] 'process raft request' (duration: 223.024514ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:28.833056Z","caller":"traceutil/trace.go:171","msg":"trace[481402685] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"235.266241ms","start":"2026-04-23T16:31:28.597756Z","end":"2026-04-23T16:31:28.833022Z","steps":["trace[481402685] 'process raft request' (duration: 235.05042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:29.595361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.202898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:31:29.595442Z","caller":"traceutil/trace.go:171","msg":"trace[649150593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"256.335388ms","start":"2026-04-23T16:31:29.339089Z","end":"2026-04-23T16:31:29.595424Z","steps":["trace[649150593] 'range keys from in-memory index tree' (duration: 256.099038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:30.839628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311567735387165707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:31:31.340369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311567735387165707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T16:31:31.676593Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.747720492s","expected-duration":"1s"} {"level":"info","ts":"2026-04-23T16:31:31.676967Z","caller":"traceutil/trace.go:171","msg":"trace[329778023] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.748170383s","start":"2026-04-23T16:31:29.928777Z","end":"2026-04-23T16:31:31.676947Z","steps":["trace[329778023] 'process raft request' (duration: 1.748042643s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.677132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:29.928757Z","time spent":"1.748288173s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:31:31.906782Z","caller":"traceutil/trace.go:171","msg":"trace[1657303793] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"1.567150642s","start":"2026-04-23T16:31:30.339604Z","end":"2026-04-23T16:31:31.906755Z","steps":["trace[1657303793] 'read index received' (duration: 1.337323173s)","trace[1657303793] 'applied index is now lower than readState.Index' (duration: 229.826429ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:31.907074Z","caller":"traceutil/trace.go:171","msg":"trace[2110548621] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.763309067s","start":"2026-04-23T16:31:30.143736Z","end":"2026-04-23T16:31:31.907045Z","steps":["trace[2110548621] 'process raft request' (duration: 1.762861426s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.907139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.567504033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:31:31.907201Z","caller":"traceutil/trace.go:171","msg":"trace[561660919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.567628563s","start":"2026-04-23T16:31:30.339546Z","end":"2026-04-23T16:31:31.907175Z","steps":["trace[561660919] 'agreement among raft nodes before linearized reading' (duration: 1.567530743s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.907242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:30.143704Z","time spent":"1.763449748s","remote":"127.0.0.1:42130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:31:31.907307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.714251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:31:31.907299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:30.339524Z","time spent":"1.567762484s","remote":"127.0.0.1:41920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T16:31:31.907401Z","caller":"traceutil/trace.go:171","msg":"trace[1629501840] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"108.894591ms","start":"2026-04-23T16:31:31.798486Z","end":"2026-04-23T16:31:31.90738Z","steps":["trace[1629501840] 'agreement among raft nodes before linearized reading' (duration: 108.724811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.907492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.072402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:31:31.907601Z","caller":"traceutil/trace.go:171","msg":"trace[171495208] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"109.227112ms","start":"2026-04-23T16:31:31.798352Z","end":"2026-04-23T16:31:31.907579Z","steps":["trace[171495208] 'agreement among raft nodes before linearized reading' (duration: 109.057772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.907573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.835136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:31:31.907795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.518901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:31:31.907831Z","caller":"traceutil/trace.go:171","msg":"trace[1440110202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:628; }","duration":"108.564271ms","start":"2026-04-23T16:31:31.799259Z","end":"2026-04-23T16:31:31.907824Z","steps":["trace[1440110202] 'agreement among raft nodes before linearized reading' (duration: 108.504721ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:31.90782Z","caller":"traceutil/trace.go:171","msg":"trace[533196505] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:628; }","duration":"156.111086ms","start":"2026-04-23T16:31:31.751614Z","end":"2026-04-23T16:31:31.907725Z","steps":["trace[533196505] 'agreement among raft nodes before linearized reading' (duration: 155.720635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.907801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067728636s","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-04-23T16:31:31.907964Z","caller":"traceutil/trace.go:171","msg":"trace[984532684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.067902287s","start":"2026-04-23T16:31:30.840048Z","end":"2026-04-23T16:31:31.90795Z","steps":["trace[984532684] 'agreement among raft nodes before linearized reading' (duration: 1.067659336s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.907974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070386742s","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-04-23T16:31:31.908059Z","caller":"traceutil/trace.go:171","msg":"trace[902878964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"1.070487962s","start":"2026-04-23T16:31:30.837556Z","end":"2026-04-23T16:31:31.908044Z","steps":["trace[902878964] 'agreement among raft nodes before linearized reading' (duration: 1.070295561s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.907513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145177848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-23T16:31:31.908156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.390547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:31:31.908205Z","caller":"traceutil/trace.go:171","msg":"trace[384406297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"1.145906299s","start":"2026-04-23T16:31:30.762246Z","end":"2026-04-23T16:31:31.908152Z","steps":["trace[384406297] 'agreement among raft nodes before linearized reading' (duration: 1.144959827s)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:31:31.908223Z","caller":"traceutil/trace.go:171","msg":"trace[687196538] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:628; }","duration":"156.505537ms","start":"2026-04-23T16:31:31.751702Z","end":"2026-04-23T16:31:31.908207Z","steps":["trace[687196538] 'agreement among raft nodes before linearized reading' (duration: 156.419847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.908264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.215885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T16:31:31.908264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:30.762223Z","time spent":"1.146023739s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T16:31:31.908293Z","caller":"traceutil/trace.go:171","msg":"trace[999246441] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:628; }","duration":"156.262696ms","start":"2026-04-23T16:31:31.752023Z","end":"2026-04-23T16:31:31.908286Z","steps":["trace[999246441] 'agreement among raft nodes before linearized reading' (duration: 155.298614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.908012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:30.840037Z","time spent":"1.067961637s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T16:31:31.907691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025268042s","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-04-23T16:31:31.908581Z","caller":"traceutil/trace.go:171","msg":"trace[1773494185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.026201884s","start":"2026-04-23T16:31:30.882366Z","end":"2026-04-23T16:31:31.908567Z","steps":["trace[1773494185] 'agreement among raft nodes before linearized reading' (duration: 1.025191182s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:31.908619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:30.882339Z","time spent":"1.026268904s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-23T16:31:31.908119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:30.837545Z","time spent":"1.070563872s","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T16:31:33.919253Z","caller":"traceutil/trace.go:171","msg":"trace[707219561] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"578.624882ms","start":"2026-04-23T16:31:33.3406Z","end":"2026-04-23T16:31:33.919225Z","steps":["trace[707219561] 'read index received' (duration: 499.676307ms)","trace[707219561] 'applied index is now lower than readState.Index' (duration: 78.947525ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:33.919301Z","caller":"traceutil/trace.go:171","msg":"trace[1811268959] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"724.362714ms","start":"2026-04-23T16:31:33.194898Z","end":"2026-04-23T16:31:33.91926Z","steps":["trace[1811268959] 'process raft request' (duration: 645.354989ms)","trace[1811268959] 'compare' (duration: 78.840424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:31:33.919454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:33.194874Z","time spent":"724.504684ms","remote":"127.0.0.1:42006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T16:31:33.919482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.863992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T16:31:33.91957Z","caller":"traceutil/trace.go:171","msg":"trace[2027095384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"579.011142ms","start":"2026-04-23T16:31:33.340539Z","end":"2026-04-23T16:31:33.919551Z","steps":["trace[2027095384] 'agreement among raft nodes before linearized reading' (duration: 578.816102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:33.919619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:33.340514Z","time spent":"579.095042ms","remote":"127.0.0.1:41920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-23T16:31:46.124885Z","caller":"traceutil/trace.go:171","msg":"trace[1682598635] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"130.847769ms","start":"2026-04-23T16:31:45.99401Z","end":"2026-04-23T16:31:46.124858Z","steps":["trace[1682598635] 'process raft request' (duration: 130.740018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:46.125144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.642871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T16:31:46.12522Z","caller":"traceutil/trace.go:171","msg":"trace[995689089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"131.775791ms","start":"2026-04-23T16:31:45.993429Z","end":"2026-04-23T16:31:46.125205Z","steps":["trace[995689089] 'agreement among raft nodes before linearized reading' (duration: 131.538891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:46.125247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.579755ms","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-04-23T16:31:46.125371Z","caller":"traceutil/trace.go:171","msg":"trace[966753737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"115.739616ms","start":"2026-04-23T16:31:46.009588Z","end":"2026-04-23T16:31:46.125328Z","steps":["trace[966753737] 'agreement among raft nodes before linearized reading' (duration: 115.432915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:48.630474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15311567735387166071,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T16:31:48.754225Z","caller":"traceutil/trace.go:171","msg":"trace[1764288260] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"683.213191ms","start":"2026-04-23T16:31:48.070988Z","end":"2026-04-23T16:31:48.754201Z","steps":["trace[1764288260] 'process raft request' (duration: 682.984361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:48.754377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:48.070959Z","time spent":"683.341032ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T16:31:49.026077Z","caller":"traceutil/trace.go:171","msg":"trace[1335218652] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:811; }","duration":"896.594133ms","start":"2026-04-23T16:31:48.12946Z","end":"2026-04-23T16:31:49.026054Z","steps":["trace[1335218652] 'read index received' (duration: 624.559821ms)","trace[1335218652] 'applied index is now lower than readState.Index' (duration: 272.033302ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T16:31:49.026241Z","caller":"traceutil/trace.go:171","msg":"trace[1690932915] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"868.615061ms","start":"2026-04-23T16:31:48.157572Z","end":"2026-04-23T16:31:49.026187Z","steps":["trace[1690932915] 'process raft request' (duration: 868.33038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:49.026426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:48.157531Z","time spent":"868.794471ms","remote":"127.0.0.1:42204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T16:31:49.026448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.967573ms","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-04-23T16:31:49.026501Z","caller":"traceutil/trace.go:171","msg":"trace[291059814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"897.037833ms","start":"2026-04-23T16:31:48.129446Z","end":"2026-04-23T16:31:49.026484Z","steps":["trace[291059814] 'agreement among raft nodes before linearized reading' (duration: 896.851673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:49.026499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.400408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-23T16:31:49.02657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:48.129425Z","time spent":"897.117214ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T16:31:49.026796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.793514ms","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":"warn","ts":"2026-04-23T16:31:49.026844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.435794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T16:31:49.026889Z","caller":"traceutil/trace.go:171","msg":"trace[1776130540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"838.534054ms","start":"2026-04-23T16:31:48.188341Z","end":"2026-04-23T16:31:49.026875Z","steps":["trace[1776130540] 'agreement among raft nodes before linearized reading' (duration: 838.415894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:49.026939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:48.188313Z","time spent":"838.617164ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-23T16:31:49.027311Z","caller":"traceutil/trace.go:171","msg":"trace[119726374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"886.25248ms","start":"2026-04-23T16:31:48.141046Z","end":"2026-04-23T16:31:49.027298Z","steps":["trace[119726374] 'agreement among raft nodes before linearized reading' (duration: 885.277968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:49.027384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:48.141025Z","time spent":"886.34622ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-23T16:31:49.027682Z","caller":"traceutil/trace.go:171","msg":"trace[10705570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"815.919874ms","start":"2026-04-23T16:31:48.210933Z","end":"2026-04-23T16:31:49.026853Z","steps":["trace[10705570] 'agreement among raft nodes before linearized reading' (duration: 815.681374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T16:31:49.027731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T16:31:48.210914Z","time spent":"816.806807ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T16:32:23.10839Z","caller":"traceutil/trace.go:171","msg":"trace[2100712352] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"133.073503ms","start":"2026-04-23T16:32:22.975296Z","end":"2026-04-23T16:32:23.108369Z","steps":["trace[2100712352] 'read index received' (duration: 132.880743ms)","trace[2100712352] 'applied index is now lower than readState.Index' (duration: 192.15µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T16:32:23.108586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.277845ms","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-04-23T16:32:23.108625Z","caller":"traceutil/trace.go:171","msg":"trace[1862641581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"133.396265ms","start":"2026-04-23T16:32:22.975217Z","end":"2026-04-23T16:32:23.108614Z","steps":["trace[1862641581] 'agreement among raft nodes before linearized reading' (duration: 133.232943ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:32:23.108627Z","caller":"traceutil/trace.go:171","msg":"trace[1752969778] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"204.431402ms","start":"2026-04-23T16:32:22.904161Z","end":"2026-04-23T16:32:23.108592Z","steps":["trace[1752969778] 'process raft request' (duration: 204.08746ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T16:32:37.617023Z","caller":"traceutil/trace.go:171","msg":"trace[1062801691] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"215.584876ms","start":"2026-04-23T16:32:37.401416Z","end":"2026-04-23T16:32:37.617001Z","steps":["trace[1062801691] 'process raft request' (duration: 215.408365ms)"],"step_count":1}